You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/06/04 02:24:12 UTC

[JENKINS] Lucene-Solr-6.x-Windows (32bit/jdk1.8.0_92) - Build # 228 - Still Failing!

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/228/
Java: 32bit/jdk1.8.0_92 -client -XX:+UseParallelGC

1 tests failed.
FAILED:  org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay

Error Message:


Stack Trace:
java.util.concurrent.TimeoutException
	at __randomizedtesting.SeedInfo.seed([76EA0A5A9CBB0D95:974BDDFF5D9201F]:0)
	at org.apache.solr.common.cloud.ZkStateReader.waitForState(ZkStateReader.java:1200)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:126)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:54)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 11148 lines...]
   [junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest
   [junit4]   2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.overseer.ZkStateReaderTest_76EA0A5A9CBB0D95-001\init-core-data-001
   [junit4]   2> 785790 INFO  (SUITE-ZkStateReaderTest-seed#[76EA0A5A9CBB0D95]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 785793 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
   [junit4]   2> 785796 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 785799 INFO  (Thread-2334) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 785799 INFO  (Thread-2334) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 785899 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.ZkTestServer start zk server on port:64463
   [junit4]   2> 785899 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 785900 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 785910 INFO  (zkCallback-1231-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@19f43b2 name:ZooKeeperConnection Watcher:127.0.0.1:64463 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 785911 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 785911 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 785914 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x155191a6cd90000, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 785916 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 785917 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 785921 INFO  (zkCallback-1232-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3cdbb6 name:ZooKeeperConnection Watcher:127.0.0.1:64463 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 785921 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 785922 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 785922 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 785928 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x155191a6cd90001, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 785933 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 785935 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 785938 INFO  (zkCallback-1233-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@983310 name:ZooKeeperConnection Watcher:127.0.0.1:64463/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 785938 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 785939 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 785940 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 785946 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 785953 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 785960 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 785964 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 785968 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 785970 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 785973 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 785982 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 785982 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 785987 INFO  (zkCallback-1233-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 785992 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 785995 INFO  (zkCallback-1233-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [0])
   [junit4]   2> 785998 INFO  (zkCallback-1233-thread-1) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 786001 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x155191a6cd90002, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 786014 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64463 64463
   [junit4]   2> 786018 INFO  (Thread-2334) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64463 64463
   [junit4]   2> 786022 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
   [junit4]   2> 786032 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 786034 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 786036 INFO  (Thread-2335) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 786036 INFO  (Thread-2335) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 786136 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.ZkTestServer start zk server on port:64475
   [junit4]   2> 786136 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 786138 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 786149 INFO  (zkCallback-1235-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@9388ee name:ZooKeeperConnection Watcher:127.0.0.1:64475 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 786149 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 786150 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 786154 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 786155 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 786168 INFO  (zkCallback-1236-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@69af29 name:ZooKeeperConnection Watcher:127.0.0.1:64475 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 786168 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 786168 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 786168 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 786180 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 786181 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 786198 INFO  (zkCallback-1237-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@110bde0 name:ZooKeeperConnection Watcher:127.0.0.1:64475/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 786198 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 786198 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 786201 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 786205 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 786211 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 786216 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 786222 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 786228 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 786230 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 786235 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 786241 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 786241 INFO  (zkCallback-1237-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 786247 INFO  (zkCallback-1237-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0])
   [junit4]   2> 786254 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 786257 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 786259 INFO  (zkCallback-1237-thread-1) [    ] o.a.s.c.c.ZkStateReader Uninteresting collection c1
   [junit4]   2> 786260 INFO  (zkCallback-1237-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0])
   [junit4]   2> 786264 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 786266 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x155191a6dc50002, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 786272 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64475 64475
   [junit4]   2> 786277 INFO  (Thread-2335) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64475 64475
   [junit4]   2> 786285 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 786292 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
   [junit4]   2> 786294 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 786295 INFO  (Thread-2336) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 786295 INFO  (Thread-2336) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 786395 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.ZkTestServer start zk server on port:64487
   [junit4]   2> 786395 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 786396 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 786434 INFO  (zkCallback-1239-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@100d0e3 name:ZooKeeperConnection Watcher:127.0.0.1:64487 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 786435 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 786435 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 786468 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 786469 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 786475 INFO  (zkCallback-1240-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b90cc9 name:ZooKeeperConnection Watcher:127.0.0.1:64487 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 786475 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 786475 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 786475 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 786491 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 786502 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 786522 INFO  (zkCallback-1241-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5f3646 name:ZooKeeperConnection Watcher:127.0.0.1:64487/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 786522 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 786522 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 786522 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 786541 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 786545 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 786548 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 786556 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 786560 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 786561 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 786564 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 786567 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 786590 INFO  (zkCallback-1241-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 786594 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 786596 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 786605 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64487 64487
   [junit4]   2> 786609 INFO  (Thread-2336) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64487 64487
   [junit4]   2> 786610 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
   [junit4]   2> 786619 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 786621 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 786622 INFO  (Thread-2337) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 786622 INFO  (Thread-2337) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 786721 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.ZkTestServer start zk server on port:64499
   [junit4]   2> 786721 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 786721 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 786737 INFO  (zkCallback-1243-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7d0011 name:ZooKeeperConnection Watcher:127.0.0.1:64499 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 786737 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 786738 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 786741 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x155191a70140000, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 786743 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 786745 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 786750 INFO  (zkCallback-1244-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@db667 name:ZooKeeperConnection Watcher:127.0.0.1:64499 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 786750 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 786751 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 786751 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 786797 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 786798 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 786803 INFO  (zkCallback-1245-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@d2905 name:ZooKeeperConnection Watcher:127.0.0.1:64499/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 786803 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 786804 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 786804 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 786812 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 786817 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 786819 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 786831 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 786840 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 786842 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 786845 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 786852 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 786852 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 786856 INFO  (zkCallback-1245-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 786860 INFO  (zkCallback-1245-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0])
   [junit4]   2> 786869 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 786870 INFO  (zkCallback-1245-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [0])
   [junit4]   2> 786877 INFO  (zkCallback-1245-thread-1) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 786878 INFO  (zkCallback-1245-thread-2) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0])
   [junit4]   2> 786892 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64499 64499
   [junit4]   2> 786895 INFO  (Thread-2337) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64499 64499
   [junit4]   2> 786897 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 786903 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
   [junit4]   2> 786905 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 786907 INFO  (Thread-2338) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 786907 INFO  (Thread-2338) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 787005 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.ZkTestServer start zk server on port:64511
   [junit4]   2> 787005 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 787006 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 787023 INFO  (zkCallback-1247-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@199315c name:ZooKeeperConnection Watcher:127.0.0.1:64511 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 787023 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 787023 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 787033 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 787039 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 787049 INFO  (zkCallback-1248-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1ad5a05 name:ZooKeeperConnection Watcher:127.0.0.1:64511 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 787049 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 787053 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 787053 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 787077 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 787084 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 787084 INFO  (zkCallback-1249-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b3f935 name:ZooKeeperConnection Watcher:127.0.0.1:64511/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 787084 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 787084 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 787084 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 787101 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 787105 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 787117 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 787129 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 787153 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 787171 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 787173 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 787176 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 787176 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 787182 INFO  (zkCallback-1249-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 787189 INFO  (zkCallback-1249-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0])
   [junit4]   2> 787192 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 787193 INFO  (zkCallback-1249-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [0])
   [junit4]   2> 787194 INFO  (zkCallback-1249-thread-1) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 787196 INFO  (zkCallback-1249-thread-2) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0])
   [junit4]   2> 817200 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x155191a712d0002, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 817203 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64511 64511
   [junit4]   2> 817207 INFO  (Thread-2338) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64511 64511
   [junit4]   2> 817209 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithTimeDelay -Dtests.seed=76EA0A5A9CBB0D95 -Dtests.slow=true -Dtests.locale=is -Dtests.timezone=Australia/Lindeman -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   30.3s J0 | ZkStateReaderTest.testStateFormatUpdateWithTimeDelay <<<
   [junit4]    > Throwable #1: java.util.concurrent.TimeoutException
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([76EA0A5A9CBB0D95:974BDDFF5D9201F]:0)
   [junit4]    > 	at org.apache.solr.common.cloud.ZkStateReader.waitForState(ZkStateReader.java:1200)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:126)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:54)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 817219 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> 817224 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 817224 INFO  (Thread-2339) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 817224 INFO  (Thread-2339) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 817325 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.ZkTestServer start zk server on port:64631
   [junit4]   2> 817325 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 817327 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 817336 INFO  (zkCallback-1251-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1b7148b name:ZooKeeperConnection Watcher:127.0.0.1:64631 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 817336 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 817337 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 817340 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 817342 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 817349 INFO  (zkCallback-1252-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1429f22 name:ZooKeeperConnection Watcher:127.0.0.1:64631 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 817349 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 817349 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 817349 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 817354 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x155191ae7980001, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 817355 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 817357 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 817362 INFO  (zkCallback-1253-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1a90fa2 name:ZooKeeperConnection Watcher:127.0.0.1:64631/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 817362 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 817363 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 817364 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 817370 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 817373 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 817377 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 817381 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 817384 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 817386 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 817388 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 817392 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 817400 INFO  (zkCallback-1253-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 817403 INFO  (zkCallback-1253-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0])
   [junit4]   2> 817410 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 817413 INFO  (zkCallback-1253-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0])
   [junit4]   2> 817429 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64631 64631
   [junit4]   2> 817432 INFO  (Thread-2339) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64631 64631
   [junit4]   2> 817444 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> 817446 INFO  (SUITE-ZkStateReaderTest-seed#[76EA0A5A9CBB0D95]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.overseer.ZkStateReaderTest_76EA0A5A9CBB0D95-001
   [junit4]   2> Jun 04, 2016 1:50:41 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 4 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {}, docValues:{}, maxPointsInLeafNode=808, maxMBSortInHeap=6.795521191627375, sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=is, timezone=Australia/Lindeman
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_92 (32-bit)/cpus=3,threads=1,free=89746224,total=292290560
   [junit4]   2> NOTE: All tests run in this JVM: [TestCodecSupport, TestFieldCacheReopen, BadComponentTest, TestTrackingShardHandlerFactory, SliceStateTest, DistribCursorPagingTest, PolyFieldTest, CircularListTest, TestNumericTerms64, TestMergePolicyConfig, AssignTest, TestRuleBasedAuthorizationPlugin, ActionThrottleTest, PrimUtilsTest, TestReplicaProperties, TestDocSet, TestSortByMinMaxFunction, TestSerializedLuceneMatchVersion, MBeansHandlerTest, TestTolerantSearch, RulesTest, TestArbitraryIndexDir, TestMaxScoreQueryParser, TestRecoveryHdfs, TestFastLRUCache, TestNRTOpen, ClusterStateTest, CoreAdminRequestStatusTest, SimpleFacetsTest, TestPerFieldSimilarityClassic, ResponseHeaderTest, SoftAutoCommitTest, ScriptEngineTest, SolrRequestParserTest, RankQueryTest, TestSolrCloudWithKerberosAlt, ConnectionManagerTest, CdcrUpdateLogTest, TestDistribIDF, StatelessScriptUpdateProcessorFactoryTest, BasicAuthIntegrationTest, TestMinMaxOnMultiValuedField, RequiredFieldsTest, SampleTest, TestConfigSetsAPIExclusivity, IndexSchemaTest, TestManagedSchemaAPI, SystemInfoHandlerTest, PreAnalyzedFieldTest, SharedFSAutoReplicaFailoverTest, TestShortCircuitedRequests, HighlighterTest, TestLuceneMatchVersion, SolrTestCaseJ4Test, MigrateRouteKeyTest, TestSolrConfigHandlerConcurrent, SimpleMLTQParserTest, TestSchemaSimilarityResource, QueryEqualityTest, TestLockTree, TestReqParamsAPI, TestInfoStreamLogging, HdfsChaosMonkeyNothingIsSafeTest, AnalyticsMergeStrategyTest, TestDocTermOrds, DeleteLastCustomShardedReplicaTest, HdfsDirectoryFactoryTest, DistributedFacetPivotSmallTest, SimplePostToolTest, TermsComponentTest, BlockJoinFacetDistribTest, CloudExitableDirectoryReaderTest, TestJsonRequest, ClassificationUpdateProcessorFactoryTest, SolrIndexConfigTest, TestScoreJoinQPNoScore, TestExtendedDismaxParser, DistributedExpandComponentTest, TestSearcherReuse, TestSolrQueryParserResource, TestNoOpRegenerator, TestLRUCache, TestSubQueryTransformerDistrib, QueryElevationComponentTest, TestSortingResponseWriter, TestDistributedSearch, TestSolrCLIRunExample, HdfsUnloadDistributedZkTest, TestAddFieldRealTimeGet, TestSubQueryTransformer, CollectionStateFormat2Test, TestWordDelimiterFilterFactory, TestObjectReleaseTracker, TestLegacyFieldCache, TestHighFrequencyDictionaryFactory, TestSolrFieldCacheMBean, JSONWriterTest, ZkStateReaderTest]
   [junit4] Completed [186/613 (1!)] on J0 in 31.97s, 6 tests, 1 error <<< FAILURES!

[...truncated 1380 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:740: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:684: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:59: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build.xml:233: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\common-build.xml:531: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:1427: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:984: There were test failures: 613 suites (10 ignored), 2567 tests, 1 error, 124 ignored (80 assumptions) [seed: 76EA0A5A9CBB0D95]

Total time: 68 minutes 4 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any