lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-Tests-6.x - Build # 321 - Still Failing
Date Thu, 07 Jul 2016 09:51:49 GMT
Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/321/

5 tests failed.
FAILED:  org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy

Error Message:
expected:<2> but was:<1>

Stack Trace:
java.lang.AssertionError: expected:<2> but was:<1>
	at __randomizedtesting.SeedInfo.seed([17E18886733CD59C:B81AA6A7D7176CA0]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.failNotEquals(Assert.java:647)
	at org.junit.Assert.assertEquals(Assert.java:128)
	at org.junit.Assert.assertEquals(Assert.java:472)
	at org.junit.Assert.assertEquals(Assert.java:456)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:130)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy(ZkStateReaderTest.java:58)
	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)


FAILED:  org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh

Error Message:
Could not find collection : c1

Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : c1
	at __randomizedtesting.SeedInfo.seed([17E18886733CD59C:85BF971A35C1359]:0)
	at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:192)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:129)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh(ZkStateReaderTest.java:43)
	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)


FAILED:  org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy

Error Message:
Could not find collection : c1

Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : c1
	at __randomizedtesting.SeedInfo.seed([17E18886733CD59C:7CAE28FB0A3308A6]:0)
	at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:192)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:129)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy(ZkStateReaderTest.java:48)
	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)


FAILED:  org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF

Error Message:


Stack Trace:
java.lang.NullPointerException
	at __randomizedtesting.SeedInfo.seed([17E18886733CD59C:868ACA8E311873E0]:0)
	at org.apache.lucene.store.RAMInputStream.readByte(RAMInputStream.java:69)
	at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEof(HdfsDirectoryTest.java:158)
	at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF(HdfsDirectoryTest.java:150)
	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)


FAILED:  org.apache.solr.cloud.overseer.ZkStateReaderTest.testExternalCollectionWatchedNotWatched

Error Message:


Stack Trace:
java.lang.NullPointerException
	at __randomizedtesting.SeedInfo.seed([17E18886733CD59C:1C5A79AA22652335]:0)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testExternalCollectionWatchedNotWatched(ZkStateReaderTest.java:167)
	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 10929 lines...]
   [junit4] Suite: org.apache.solr.store.hdfs.HdfsDirectoryTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_17E18886733CD59C-001/init-core-data-001
   [junit4]   2> 501678 INFO  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   1> Formatting using clusterid: testClusterID
   [junit4]   2> 501746 WARN  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
   [junit4]   2> 501789 WARN  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 501798 INFO  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.m.log jetty-6.1.26
   [junit4]   2> 501821 INFO  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/hdfs to ./temp/Jetty_localhost_58259_hdfs____.3t6ao3/webapp
   [junit4]   2> 502387 INFO  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:58259
   [junit4]   2> 502847 WARN  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 502849 INFO  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.m.log jetty-6.1.26
   [junit4]   2> 502872 INFO  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_47969_datanode____dk2ta8/webapp
   [junit4]   2> 503609 INFO  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:47969
   [junit4]   2> 503926 WARN  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 503928 INFO  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.m.log jetty-6.1.26
   [junit4]   2> 504028 INFO  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_44855_datanode____.tazao1/webapp
   [junit4]   2> 504289 INFO  (IPC Server handler 4 on 39690) [    ] BlockStateChange BLOCK* processReport: from storage DS-a78c1649-228e-4828-b766-f4cd2354f79a node DatanodeRegistration(127.0.0.1:56074, datanodeUuid=771896b3-fc60-4e66-9259-f670e6c4c60d, infoPort=40544, infoSecurePort=0, ipcPort=46188, storageInfo=lv=-56;cid=testClusterID;nsid=343777695;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs
   [junit4]   2> 504290 INFO  (IPC Server handler 4 on 39690) [    ] BlockStateChange BLOCK* processReport: from storage DS-8aa84608-ac9b-4801-9738-682bd18af1d5 node DatanodeRegistration(127.0.0.1:56074, datanodeUuid=771896b3-fc60-4e66-9259-f670e6c4c60d, infoPort=40544, infoSecurePort=0, ipcPort=46188, storageInfo=lv=-56;cid=testClusterID;nsid=343777695;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs
   [junit4]   2> 504765 INFO  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:44855
   [junit4]   2> 504969 INFO  (IPC Server handler 5 on 39690) [    ] BlockStateChange BLOCK* processReport: from storage DS-75d239cf-8ae6-4521-b1e9-c1dab4f927c6 node DatanodeRegistration(127.0.0.1:43043, datanodeUuid=daeb9895-a929-4e79-a4eb-0e57f2a6ef1d, infoPort=46917, infoSecurePort=0, ipcPort=54789, storageInfo=lv=-56;cid=testClusterID;nsid=343777695;c=0), blocks: 0, hasStaleStorage: true, processing time: 1 msecs
   [junit4]   2> 504970 INFO  (IPC Server handler 5 on 39690) [    ] BlockStateChange BLOCK* processReport: from storage DS-9555fedb-0270-4c0d-9645-b88ccde72ed1 node DatanodeRegistration(127.0.0.1:43043, datanodeUuid=daeb9895-a929-4e79-a4eb-0e57f2a6ef1d, infoPort=46917, infoSecurePort=0, ipcPort=54789, storageInfo=lv=-56;cid=testClusterID;nsid=343777695;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs
   [junit4]   2> 505234 INFO  (TEST-HdfsDirectoryTest.testRandomAccessWrites-seed#[17E18886733CD59C]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testRandomAccessWrites
   [junit4]   1> Working on pass [0] contains [false]
   [junit4]   2> 505397 INFO  (IPC Server handler 5 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56074 is added to blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-9555fedb-0270-4c0d-9645-b88ccde72ed1:NORMAL:127.0.0.1:43043|RBW], ReplicaUC[[DISK]DS-a78c1649-228e-4828-b766-f4cd2354f79a:NORMAL:127.0.0.1:56074|RBW]]} size 0
   [junit4]   2> 505399 INFO  (IPC Server handler 6 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43043 is added to blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-a78c1649-228e-4828-b766-f4cd2354f79a:NORMAL:127.0.0.1:56074|RBW], ReplicaUC[[DISK]DS-75d239cf-8ae6-4521-b1e9-c1dab4f927c6:NORMAL:127.0.0.1:43043|FINALIZED]]} size 0
   [junit4]   1> Working on pass [1] contains [false]
   [junit4]   2> 514904 INFO  (IPC Server handler 4 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43043 is added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-8aa84608-ac9b-4801-9738-682bd18af1d5:NORMAL:127.0.0.1:56074|RBW], ReplicaUC[[DISK]DS-9555fedb-0270-4c0d-9645-b88ccde72ed1:NORMAL:127.0.0.1:43043|FINALIZED]]} size 0
   [junit4]   2> 514905 INFO  (IPC Server handler 2 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56074 is added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-8aa84608-ac9b-4801-9738-682bd18af1d5:NORMAL:127.0.0.1:56074|RBW], ReplicaUC[[DISK]DS-9555fedb-0270-4c0d-9645-b88ccde72ed1:NORMAL:127.0.0.1:43043|FINALIZED]]} size 0
   [junit4]   1> Working on pass [2] contains [false]
   [junit4]   2> 518029 INFO  (IPC Server handler 9 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43043 is added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-8aa84608-ac9b-4801-9738-682bd18af1d5:NORMAL:127.0.0.1:56074|RBW], ReplicaUC[[DISK]DS-75d239cf-8ae6-4521-b1e9-c1dab4f927c6:NORMAL:127.0.0.1:43043|FINALIZED]]} size 0
   [junit4]   2> 518030 INFO  (IPC Server handler 8 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56074 is added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-75d239cf-8ae6-4521-b1e9-c1dab4f927c6:NORMAL:127.0.0.1:43043|FINALIZED], ReplicaUC[[DISK]DS-a78c1649-228e-4828-b766-f4cd2354f79a:NORMAL:127.0.0.1:56074|FINALIZED]]} size 0
   [junit4]   1> Working on pass [3] contains [false]
   [junit4]   2> 522709 INFO  (IPC Server handler 7 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56074 is added to blk_1073741828_1004{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-75d239cf-8ae6-4521-b1e9-c1dab4f927c6:NORMAL:127.0.0.1:43043|RBW], ReplicaUC[[DISK]DS-8aa84608-ac9b-4801-9738-682bd18af1d5:NORMAL:127.0.0.1:56074|FINALIZED]]} size 0
   [junit4]   2> 522712 INFO  (IPC Server handler 9 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43043 is added to blk_1073741828_1004{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-8aa84608-ac9b-4801-9738-682bd18af1d5:NORMAL:127.0.0.1:56074|FINALIZED], ReplicaUC[[DISK]DS-9555fedb-0270-4c0d-9645-b88ccde72ed1:NORMAL:127.0.0.1:43043|FINALIZED]]} size 0
   [junit4]   1> Working on pass [4] contains [false]
   [junit4]   2> 527141 INFO  (IPC Server handler 6 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43043 is added to blk_1073741829_1005{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-75d239cf-8ae6-4521-b1e9-c1dab4f927c6:NORMAL:127.0.0.1:43043|RBW], ReplicaUC[[DISK]DS-8aa84608-ac9b-4801-9738-682bd18af1d5:NORMAL:127.0.0.1:56074|RBW]]} size 0
   [junit4]   2> 527144 INFO  (IPC Server handler 6 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56074 is added to blk_1073741829_1005 size 4491526
   [junit4]   1> Working on pass [5] contains [false]
   [junit4]   2> 528303 INFO  (IPC Server handler 8 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43043 is added to blk_1073741830_1006{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-9555fedb-0270-4c0d-9645-b88ccde72ed1:NORMAL:127.0.0.1:43043|RBW], ReplicaUC[[DISK]DS-8aa84608-ac9b-4801-9738-682bd18af1d5:NORMAL:127.0.0.1:56074|RBW]]} size 0
   [junit4]   2> 528304 INFO  (IPC Server handler 2 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56074 is added to blk_1073741830_1006{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-9555fedb-0270-4c0d-9645-b88ccde72ed1:NORMAL:127.0.0.1:43043|RBW], ReplicaUC[[DISK]DS-8aa84608-ac9b-4801-9738-682bd18af1d5:NORMAL:127.0.0.1:56074|RBW]]} size 0
   [junit4]   1> Working on pass [6] contains [false]
   [junit4]   2> 529517 INFO  (IPC Server handler 9 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56074 is added to blk_1073741831_1007{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-9555fedb-0270-4c0d-9645-b88ccde72ed1:NORMAL:127.0.0.1:43043|RBW], ReplicaUC[[DISK]DS-a78c1649-228e-4828-b766-f4cd2354f79a:NORMAL:127.0.0.1:56074|RBW]]} size 0
   [junit4]   2> 529522 INFO  (IPC Server handler 5 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43043 is added to blk_1073741831_1007{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-a78c1649-228e-4828-b766-f4cd2354f79a:NORMAL:127.0.0.1:56074|RBW], ReplicaUC[[DISK]DS-75d239cf-8ae6-4521-b1e9-c1dab4f927c6:NORMAL:127.0.0.1:43043|FINALIZED]]} size 0
   [junit4]   1> Working on pass [7] contains [false]
   [junit4]   2> 531643 INFO  (IPC Server handler 0 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43043 is added to blk_1073741832_1008{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-8aa84608-ac9b-4801-9738-682bd18af1d5:NORMAL:127.0.0.1:56074|RBW], ReplicaUC[[DISK]DS-9555fedb-0270-4c0d-9645-b88ccde72ed1:NORMAL:127.0.0.1:43043|FINALIZED]]} size 0
   [junit4]   2> 531646 INFO  (IPC Server handler 6 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56074 is added to blk_1073741832_1008{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-8aa84608-ac9b-4801-9738-682bd18af1d5:NORMAL:127.0.0.1:56074|RBW], ReplicaUC[[DISK]DS-9555fedb-0270-4c0d-9645-b88ccde72ed1:NORMAL:127.0.0.1:43043|FINALIZED]]} size 0
   [junit4]   1> Working on pass [8] contains [false]
   [junit4]   2> 532118 INFO  (IPC Server handler 1 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56074 is added to blk_1073741833_1009{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-75d239cf-8ae6-4521-b1e9-c1dab4f927c6:NORMAL:127.0.0.1:43043|RBW], ReplicaUC[[DISK]DS-a78c1649-228e-4828-b766-f4cd2354f79a:NORMAL:127.0.0.1:56074|RBW]]} size 0
   [junit4]   2> 532119 INFO  (IPC Server handler 4 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43043 is added to blk_1073741833_1009{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-75d239cf-8ae6-4521-b1e9-c1dab4f927c6:NORMAL:127.0.0.1:43043|RBW], ReplicaUC[[DISK]DS-a78c1649-228e-4828-b766-f4cd2354f79a:NORMAL:127.0.0.1:56074|RBW]]} size 0
   [junit4]   1> Working on pass [9] contains [false]
   [junit4]   2> 535660 INFO  (IPC Server handler 2 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43043 is added to blk_1073741834_1010{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-8aa84608-ac9b-4801-9738-682bd18af1d5:NORMAL:127.0.0.1:56074|RBW], ReplicaUC[[DISK]DS-9555fedb-0270-4c0d-9645-b88ccde72ed1:NORMAL:127.0.0.1:43043|RBW]]} size 0
   [junit4]   2> 535661 INFO  (IPC Server handler 1 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56074 is added to blk_1073741834_1010{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-8aa84608-ac9b-4801-9738-682bd18af1d5:NORMAL:127.0.0.1:56074|RBW], ReplicaUC[[DISK]DS-9555fedb-0270-4c0d-9645-b88ccde72ed1:NORMAL:127.0.0.1:43043|RBW]]} size 0
   [junit4]   2> 537434 INFO  (TEST-HdfsDirectoryTest.testRandomAccessWrites-seed#[17E18886733CD59C]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testRandomAccessWrites
   [junit4]   2> 537471 INFO  (TEST-HdfsDirectoryTest.testRename-seed#[17E18886733CD59C]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testRename
   [junit4]   2> 537503 INFO  (IPC Server handler 5 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56074 is added to blk_1073741835_1011{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-9555fedb-0270-4c0d-9645-b88ccde72ed1:NORMAL:127.0.0.1:43043|RBW], ReplicaUC[[DISK]DS-a78c1649-228e-4828-b766-f4cd2354f79a:NORMAL:127.0.0.1:56074|FINALIZED]]} size 0
   [junit4]   2> 537527 INFO  (IPC Server handler 8 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43043 is added to blk_1073741835_1011 size 4
   [junit4]   2> 537547 INFO  (IPC Server handler 4 on 39690) [    ] BlockStateChange BLOCK* addToInvalidates: blk_1073741835_1011 127.0.0.1:56074 127.0.0.1:43043 
   [junit4]   2> 537552 INFO  (TEST-HdfsDirectoryTest.testRename-seed#[17E18886733CD59C]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testRename
   [junit4]   2> 537602 INFO  (TEST-HdfsDirectoryTest.testWritingAndReadingAFile-seed#[17E18886733CD59C]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testWritingAndReadingAFile
   [junit4]   2> 537724 INFO  (IPC Server handler 6 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56074 is added to blk_1073741836_1012{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-9555fedb-0270-4c0d-9645-b88ccde72ed1:NORMAL:127.0.0.1:43043|RBW], ReplicaUC[[DISK]DS-8aa84608-ac9b-4801-9738-682bd18af1d5:NORMAL:127.0.0.1:56074|RBW]]} size 0
   [junit4]   2> 537728 INFO  (IPC Server handler 4 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43043 is added to blk_1073741836_1012 size 4
   [junit4]   2> 537748 INFO  (IPC Server handler 4 on 39690) [    ] BlockStateChange BLOCK* addToInvalidates: blk_1073741836_1012 127.0.0.1:56074 127.0.0.1:43043 
   [junit4]   2> 537749 INFO  (TEST-HdfsDirectoryTest.testWritingAndReadingAFile-seed#[17E18886733CD59C]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testWritingAndReadingAFile
   [junit4]   2> 537788 INFO  (TEST-HdfsDirectoryTest.testEOF-seed#[17E18886733CD59C]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testEOF
   [junit4]   2> 537963 INFO  (IPC Server handler 7 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56074 is added to blk_1073741837_1013{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-9555fedb-0270-4c0d-9645-b88ccde72ed1:NORMAL:127.0.0.1:43043|RBW], ReplicaUC[[DISK]DS-a78c1649-228e-4828-b766-f4cd2354f79a:NORMAL:127.0.0.1:56074|RBW]]} size 0
   [junit4]   2> 537964 INFO  (IPC Server handler 6 on 39690) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43043 is added to blk_1073741837_1013 size 1923072
   [junit4]   2> 537967 INFO  (TEST-HdfsDirectoryTest.testEOF-seed#[17E18886733CD59C]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testEOF
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=HdfsDirectoryTest -Dtests.method=testEOF -Dtests.seed=17E18886733CD59C -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=sr -Dtests.timezone=America/Buenos_Aires -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.22s J2 | HdfsDirectoryTest.testEOF <<<
   [junit4]    > Throwable #1: java.lang.NullPointerException
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([17E18886733CD59C:868ACA8E311873E0]:0)
   [junit4]    > 	at org.apache.lucene.store.RAMInputStream.readByte(RAMInputStream.java:69)
   [junit4]    > 	at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEof(HdfsDirectoryTest.java:158)
   [junit4]    > 	at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF(HdfsDirectoryTest.java:150)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 537970 WARN  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called
   [junit4]   2> 537990 WARN  (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_17E18886733CD59C-001/tempDir-001/hdfsBaseDir/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_17E18886733CD59C-001/tempDir-001/hdfsBaseDir/data/data4/]]  heartbeating to localhost/127.0.0.1:39690) [    ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-1889465363-10.41.0.5-1467882685394 (Datanode Uuid daeb9895-a929-4e79-a4eb-0e57f2a6ef1d) service to localhost/127.0.0.1:39690
   [junit4]   2> 538111 INFO  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
   [junit4]   2> 538246 WARN  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called
   [junit4]   2> 538274 INFO  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
   [junit4]   2> 538398 WARN  (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_17E18886733CD59C-001/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_17E18886733CD59C-001/tempDir-001/hdfsBaseDir/data/data2/]]  heartbeating to localhost/127.0.0.1:39690) [    ] o.a.h.h.s.d.DataNode BPOfferService for Block pool BP-1889465363-10.41.0.5-1467882685394 (Datanode Uuid 771896b3-fc60-4e66-9259-f670e6c4c60d) service to localhost/127.0.0.1:39690 interrupted
   [junit4]   2> 538398 WARN  (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_17E18886733CD59C-001/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_17E18886733CD59C-001/tempDir-001/hdfsBaseDir/data/data2/]]  heartbeating to localhost/127.0.0.1:39690) [    ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-1889465363-10.41.0.5-1467882685394 (Datanode Uuid 771896b3-fc60-4e66-9259-f670e6c4c60d) service to localhost/127.0.0.1:39690
   [junit4]   2> 538496 INFO  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
   [junit4]   2> 538497 WARN  (707071205@qtp-242902021-1 - Acceptor0 HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:58259) [    ] o.a.h.h.HttpServer2 HttpServer Acceptor: isRunning is false. Rechecking.
   [junit4]   2> 538498 WARN  (707071205@qtp-242902021-1 - Acceptor0 HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:58259) [    ] o.a.h.h.HttpServer2 HttpServer Acceptor: isRunning is false
   [junit4]   2> 538612 ERROR (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.a.h.m.l.MethodMetric Error invoking method getBlocksTotal
   [junit4]   2> java.lang.reflect.InvocationTargetException
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]   2> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:498)
   [junit4]   2> 	at org.apache.hadoop.metrics2.lib.MethodMetric$2.snapshot(MethodMetric.java:111)
   [junit4]   2> 	at org.apache.hadoop.metrics2.lib.MethodMetric.snapshot(MethodMetric.java:144)
   [junit4]   2> 	at org.apache.hadoop.metrics2.lib.MetricsRegistry.snapshot(MetricsRegistry.java:401)
   [junit4]   2> 	at org.apache.hadoop.metrics2.lib.MetricsSourceBuilder$1.getMetrics(MetricsSourceBuilder.java:79)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMetrics(MetricsSourceAdapter.java:194)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.updateJmxCache(MetricsSourceAdapter.java:172)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMBeanInfo(MetricsSourceAdapter.java:151)
   [junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1804)
   [junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1595)
   [junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1813)
   [junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:430)
   [junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415)
   [junit4]   2> 	at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546)
   [junit4]   2> 	at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:81)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:226)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:211)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:463)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:213)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:594)
   [junit4]   2> 	at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72)
   [junit4]   2> 	at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68)
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:171)
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:872)
   [junit4]   2> 	at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1726)
   [junit4]   2> 	at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1705)
   [junit4]   2> 	at org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:198)
   [junit4]   2> 	at org.apache.solr.store.hdfs.HdfsDirectoryTest.afterClass(HdfsDirectoryTest.java:65)
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]   2> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:498)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> Caused by: java.lang.NullPointerException
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:203)
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3370)
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:5729)
   [junit4]   2> 	... 54 more
   [junit4]   2> 538619 INFO  (SUITE-HdfsDirectoryTest-seed#[17E18886733CD59C]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_17E18886733CD59C-001
   [junit4]   2> Jul 07, 2016 9:12:02 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 34 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY, chunkSize=3273, maxDocsPerChunk=10, blockSize=2), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, chunkSize=3273, blockSize=2)), sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=sr, timezone=America/Buenos_Aires
   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=2,free=139474744,total=522190848
   [junit4]   2> NOTE: All tests run in this JVM: [FieldAnalysisRequestHandlerTest, DistributedQueryComponentOptimizationTest, UpdateParamsTest, SuggesterTSTTest, SparseHLLTest, TestSort, TestReload, LukeRequestHandlerTest, TestStressReorder, TestLocalFSCloudBackupRestore, TestFuzzyAnalyzedSuggestions, TestHdfsCloudBackupRestore, TestSolrJ, TestElisionMultitermQuery, TestXmlQParser, LeaderElectionTest, TestJsonRequest, TestCloudDeleteByQuery, TestTolerantUpdateProcessorCloud, RecoveryZkTest, HdfsCollectionsAPIDistributedZkTest, TestFieldCacheSort, TestConfigSetProperties, BasicDistributedZk2Test, DirectSolrConnectionTest, TestDynamicFieldResource, BlockCacheTest, TestManagedSchema, ShowFileRequestHandlerTest, TestClassicSimilarityFactory, PKIAuthenticationIntegrationTest, StatelessScriptUpdateProcessorFactoryTest, TestXIncludeConfig, DateRangeFieldTest, PluginInfoTest, TestDistribDocBasedVersion, TestIntervalFaceting, TestRestoreCore, LeaderFailoverAfterPartitionTest, CoreMergeIndexesAdminHandlerTest, TestJmxMonitoredMap, TestOverriddenPrefixQueryForCustomFieldType, OverseerRolesTest, RuleEngineTest, TestIndexSearcher, TestDocBasedVersionConstraints, HdfsDirectoryTest]
   [junit4] Completed [101/621 (1!)] on J2 in 46.34s, 4 tests, 1 error <<< FAILURES!

[...truncated 1308 lines...]
   [junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.overseer.ZkStateReaderTest_17E18886733CD59C-001/init-core-data-001
   [junit4]   2> 1963735 INFO  (SUITE-ZkStateReaderTest-seed#[17E18886733CD59C]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 1963746 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
   [junit4]   2> 1963747 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1963747 INFO  (Thread-5439) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1963747 INFO  (Thread-5439) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1963847 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.ZkTestServer start zk server on port:39050
   [junit4]   2> 1963847 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1963862 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1963876 INFO  (zkCallback-2462-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1f4d5db9 name:ZooKeeperConnection Watcher:127.0.0.1:39050 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1963876 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1963876 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1963881 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1963882 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1963894 INFO  (zkCallback-2463-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1d17ab69 name:ZooKeeperConnection Watcher:127.0.0.1:39050 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1963895 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1963895 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1963895 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1963910 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1963913 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1963930 INFO  (zkCallback-2464-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6430e6f8 name:ZooKeeperConnection Watcher:127.0.0.1:39050/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1963934 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1963935 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1963935 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1963936 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 1963937 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1963937 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1963938 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1963939 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1963947 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 1963947 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 1963948 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 1963949 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 1963967 INFO  (zkCallback-2464-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 1963987 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 1963987 INFO  (zkCallback-2464-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> 1963988 INFO  (zkCallback-2464-thread-1) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 1963990 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:39050 39050
   [junit4]   2> 1964091 INFO  (Thread-5439) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:39050 39050
   [junit4]   2> 1964092 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[17E18886733CD59C]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
   [junit4]   2> 1964094 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
   [junit4]   2> 1964094 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1964095 INFO  (Thread-5440) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1964095 INFO  (Thread-5440) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1964195 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.ZkTestServer start zk server on port:53775
   [junit4]   2> 1964195 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1964201 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1964203 INFO  (zkCallback-2466-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@762c9323 name:ZooKeeperConnection Watcher:127.0.0.1:53775 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1964203 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1964203 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1964204 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1964205 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1964206 INFO  (zkCallback-2467-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7398eb80 name:ZooKeeperConnection Watcher:127.0.0.1:53775 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1964206 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1964206 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1964206 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1964214 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1964215 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1964215 INFO  (zkCallback-2468-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1f1133ce name:ZooKeeperConnection Watcher:127.0.0.1:53775/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1964215 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1964216 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1964216 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1964217 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 1964218 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1964218 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1964219 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1964220 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1964220 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 1964221 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 1964222 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 1964222 INFO  (zkCallback-2468-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 1964224 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 1964232 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:53775 53775
   [junit4]   2> 1964352 INFO  (Thread-5440) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:53775 53775
   [junit4]   2> 1964355 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[17E18886733CD59C]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ZkStateReaderTest -Dtests.method=testExternalCollectionWatchedNotWatched -Dtests.seed=17E18886733CD59C -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=no -Dtests.timezone=Asia/Choibalsan -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.26s J2 | ZkStateReaderTest.testExternalCollectionWatchedNotWatched <<<
   [junit4]    > Throwable #1: java.lang.NullPointerException
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([17E18886733CD59C:1C5A79AA22652335]:0)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testExternalCollectionWatchedNotWatched(ZkStateReaderTest.java:167)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1964358 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
   [junit4]   2> 1964359 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1964359 INFO  (Thread-5441) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1964359 INFO  (Thread-5441) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1964459 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.ZkTestServer start zk server on port:57620
   [junit4]   2> 1964459 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1964461 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1964463 INFO  (zkCallback-2470-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@625965da name:ZooKeeperConnection Watcher:127.0.0.1:57620 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1964463 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1964464 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1964465 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1964466 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1964468 INFO  (zkCallback-2471-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@16bdf13 name:ZooKeeperConnection Watcher:127.0.0.1:57620 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1964468 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1964468 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1964468 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1964475 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1964476 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1964477 INFO  (zkCallback-2472-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6cb615e8 name:ZooKeeperConnection Watcher:127.0.0.1:57620/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1964477 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1964477 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1964478 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1964479 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 1964480 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1964481 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1964484 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1964484 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1964485 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 1964486 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 1964506 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 1964506 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 1964508 INFO  (zkCallback-2472-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> 1964518 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 1964520 INFO  (zkCallback-2472-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 1964522 INFO  (zkCallback-2472-thread-3) [    ] 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> 1964523 INFO  (zkCallback-2472-thread-2) [    ] 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> 1964526 INFO  (zkCallback-2472-thread-2) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 1964528 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:57620 57620
   [junit4]   2> 1964627 INFO  (Thread-5441) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:57620 57620
   [junit4]   2> 1964628 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[17E18886733CD59C]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
   [junit4]   2> 1964631 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 1964631 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1964633 INFO  (Thread-5442) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1964633 INFO  (Thread-5442) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1964733 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.ZkTestServer start zk server on port:56357
   [junit4]   2> 1964733 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1964733 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1964735 INFO  (zkCallback-2474-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@73e72cd name:ZooKeeperConnection Watcher:127.0.0.1:56357 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1964735 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1964735 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1964736 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1964739 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1964739 INFO  (zkCallback-2475-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@74004f7b name:ZooKeeperConnection Watcher:127.0.0.1:56357 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1964739 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1964739 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1964739 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1964741 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 0x155c4b696610001, 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> 1964741 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1964742 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1964743 INFO  (zkCallback-2476-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@54c9a3c4 name:ZooKeeperConnection Watcher:127.0.0.1:56357/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1964743 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1964743 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1964743 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1964744 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 1964745 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1964746 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1964746 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1964747 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1964747 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 1964748 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 1964749 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 1964751 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 1964751 INFO  (zkCallback-2476-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> 1964763 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 1964764 INFO  (zkCallback-2476-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 1964764 INFO  (zkCallback-2476-thread-2) [    ] o.a.s.c.c.ZkStateReader Uninteresting collection c1
   [junit4]   2> 1964766 INFO  (zkCallback-2476-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> 1964776 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 1964778 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:56357 56357
   [junit4]   2> 1964886 INFO  (Thread-5442) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:56357 56357
   [junit4]   2> 1964887 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[17E18886733CD59C]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithTimeDelayLazy -Dtests.seed=17E18886733CD59C -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=no -Dtests.timezone=Asia/Choibalsan -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] FAILURE 0.26s J2 | ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: expected:<2> but was:<1>
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([17E18886733CD59C:B81AA6A7D7176CA0]:0)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:130)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy(ZkStateReaderTest.java:58)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1964891 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 1964892 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1964893 INFO  (Thread-5443) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1964893 INFO  (Thread-5443) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1964993 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.ZkTestServer start zk server on port:42333
   [junit4]   2> 1964993 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1964994 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1965008 INFO  (zkCallback-2478-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@d383544 name:ZooKeeperConnection Watcher:127.0.0.1:42333 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1965008 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1965008 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1965009 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1965023 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1965042 INFO  (zkCallback-2479-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1760c50e name:ZooKeeperConnection Watcher:127.0.0.1:42333 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1965043 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1965043 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1965043 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1965045 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1965049 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1965049 INFO  (zkCallback-2480-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2bb07831 name:ZooKeeperConnection Watcher:127.0.0.1:42333/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1965049 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1965049 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1965049 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1965050 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 1965052 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1965053 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1965054 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1965055 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1965055 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 1965056 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 1965057 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 1965057 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 1965059 INFO  (zkCallback-2480-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> 1965067 INFO  (zkCallback-2480-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 1965068 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[17E18886733CD59C]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 1965069 INFO  (zkCallback-2480-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> 1965069 INFO  (zkCallback-2480-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> 1965070 INFO  (zkC

[...truncated too long message...]

1965390 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 1965398 ERROR (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[17E18886733CD59C]-EventThread) [    ] o.a.z.ClientCnxn Error while calling watcher 
   [junit4]   2> java.util.concurrent.RejectedExecutionException: Task org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$8/235265027@26704d88 rejected from org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@6372d722[Shutting down, pool size = 1, active threads = 1, queued tasks = 0, completed tasks = 1]
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2047)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:823)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:214)
   [junit4]   2> 	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:267)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
   [junit4]   2> 1965398 INFO  (zkCallback-2484-thread-2) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 1965399 WARN  (zkCallback-2484-thread-2) [    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /collections]
   [junit4]   2> 1965400 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[17E18886733CD59C]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51266 51266
   [junit4]   2> 1965519 INFO  (Thread-5444) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51266 51266
   [junit4]   2> 1965523 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[17E18886733CD59C]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithExplicitRefreshLazy -Dtests.seed=17E18886733CD59C -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=no -Dtests.timezone=Asia/Choibalsan -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.29s J2 | ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy <<<
   [junit4]    > Throwable #1: org.apache.solr.common.SolrException: Could not find collection : c1
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([17E18886733CD59C:7CAE28FB0A3308A6]:0)
   [junit4]    > 	at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:192)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:129)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy(ZkStateReaderTest.java:48)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1965524 INFO  (SUITE-ZkStateReaderTest-seed#[17E18886733CD59C]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.overseer.ZkStateReaderTest_17E18886733CD59C-001
   [junit4]   2> Jul 07, 2016 9:35:49 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=no, timezone=Asia/Choibalsan
   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=154261096,total=527958016
   [junit4]   2> NOTE: All tests run in this JVM: [FieldAnalysisRequestHandlerTest, DistributedQueryComponentOptimizationTest, UpdateParamsTest, SuggesterTSTTest, SparseHLLTest, TestSort, TestReload, LukeRequestHandlerTest, TestStressReorder, TestLocalFSCloudBackupRestore, TestFuzzyAnalyzedSuggestions, TestHdfsCloudBackupRestore, TestSolrJ, TestElisionMultitermQuery, TestXmlQParser, LeaderElectionTest, TestJsonRequest, TestCloudDeleteByQuery, TestTolerantUpdateProcessorCloud, RecoveryZkTest, HdfsCollectionsAPIDistributedZkTest, TestFieldCacheSort, TestConfigSetProperties, BasicDistributedZk2Test, DirectSolrConnectionTest, TestDynamicFieldResource, BlockCacheTest, TestManagedSchema, ShowFileRequestHandlerTest, TestClassicSimilarityFactory, PKIAuthenticationIntegrationTest, StatelessScriptUpdateProcessorFactoryTest, TestXIncludeConfig, DateRangeFieldTest, PluginInfoTest, TestDistribDocBasedVersion, TestIntervalFaceting, TestRestoreCore, LeaderFailoverAfterPartitionTest, CoreMergeIndexesAdminHandlerTest, TestJmxMonitoredMap, TestOverriddenPrefixQueryForCustomFieldType, OverseerRolesTest, RuleEngineTest, TestIndexSearcher, TestDocBasedVersionConstraints, HdfsDirectoryTest, TestNamedUpdateProcessors, TestDistributedStatsComponentCardinality, HdfsRecoveryZkTest, CollectionStateFormat2Test, TestSubQueryTransformerDistrib, GraphQueryTest, TestFieldCache, TestJettySolrRunner, TestNumericTerms64, ExitableDirectoryReaderTest, TestGraphTermsQParserPlugin, SpatialRPTFieldTypeTest, FullHLLTest, TestSmileRequest, TestCharFilters, DistributedVersionInfoTest, BinaryUpdateRequestHandlerTest, HdfsWriteToMultipleCollectionsTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestPerFieldSimilarity, TestCSVResponseWriter, DistanceUnitsTest, TestIndexingPerformance, DistribDocExpirationUpdateProcessorTest, SearchHandlerTest, AnalyticsQueryTest, CollectionsAPISolrJTest, MigrateRouteKeyTest, PrimitiveFieldTypeTest, RestartWhileUpdatingTest, SampleTest, TestSolrDeletionPolicy1, TestFastWriter, TestJmxIntegration, TestUseDocValuesAsStored, BlockJoinFacetDistribTest, TestExtendedDismaxParser, TestSolrCLIRunExample, TestPerFieldSimilarityClassic, DistributedFacetPivotSmallTest, XmlUpdateRequestHandlerTest, TestSchemaResource, TestSolrIndexConfig, AnalysisErrorHandlingTest, TestSlowCompositeReaderWrapper, AutoCommitTest, BadCopyFieldTest, TermVectorComponentDistributedTest, TestRecovery, TestReversedWildcardFilterFactory, TestReloadDeadlock, DebugComponentTest, RecoveryAfterSoftCommitTest, DirectUpdateHandlerOptimizeTest, TestCoreDiscovery, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, ShardRoutingTest, TestReplicationHandler, TestHashPartitioner, TestCoreContainer, SpellCheckComponentTest, SolrCmdDistributorTest, QueryElevationComponentTest, BadIndexSchemaTest, TestFunctionQuery, TestBadConfig, SimplePostToolTest, SignatureUpdateProcessorFactoryTest, CoreAdminHandlerTest, SpatialFilterTest, PolyFieldTest, WordBreakSolrSpellCheckerTest, TestPseudoReturnFields, TestAtomicUpdateErrorCases, QueryEqualityTest, TestRemoteStreaming, SolrInfoMBeanTest, DisMaxRequestHandlerTest, TestWriterPerf, TestValueSourceCache, MoreLikeThisHandlerTest, TestSolrQueryParser, LoggingHandlerTest, TestCollationField, UniqFieldsUpdateProcessorFactoryTest, TestPHPSerializedResponseWriter, HighlighterConfigTest, SOLR749Test, CopyFieldTest, SolrIndexConfigTest, TestSolrDeletionPolicy2, TestBinaryField, TestSearchPerf, MinimalSchemaTest, TestConfig, OutputWriterTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, SpellPossibilityIteratorTest, TestSweetSpotSimilarityFactory, TimeZoneUtilsTest, ScriptEngineTest, ChaosMonkeyNothingIsSafeTest, TestSimpleTrackingShardHandler, TestEmbeddedSolrServerConstructors, AliasIntegrationTest, CollectionsAPIAsyncDistributedZkTest, DeleteReplicaTest, OverseerStatusTest, OverseerTaskQueueTest, ReplicationFactorTest, RollingRestartTest, SSLMigrationTest, SaslZkACLProviderTest, SolrCloudExampleTest, TestDownShardTolerantSearch, TestExclusionRuleCollectionAccess, TestMiniSolrCloudClusterSSL, TestReplicaProperties, TestSSLRandomization, TestShortCircuitedRequests, TestStressCloudBlindAtomicUpdates, HdfsUnloadDistributedZkTest, TestClusterStateMutator, ZkStateReaderTest]
   [junit4] Completed [499/621 (2!)] on J2 in 2.82s, 6 tests, 1 failure, 3 errors <<< FAILURES!

[...truncated 415 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:740: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:684: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/common-build.xml:530: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:1443: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:984: There were test failures: 621 suites (10 ignored), 2642 tests, 4 errors, 1 failure, 103 ignored (88 assumptions) [seed: 17E18886733CD59C]

Total time: 82 minutes 5 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any




Mime
View raw message