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



---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to