Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7242/ Java: 64bit/jdk1.8.0_144 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
6 tests failed. FAILED: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication Error Message: found:2[index.20180326155237441, index.20180326155237810, index.properties, replication.properties, snapshot_metadata] Stack Trace: java.lang.AssertionError: found:2[index.20180326155237441, index.20180326155237810, index.properties, replication.properties, snapshot_metadata] at __randomizedtesting.SeedInfo.seed([CF1B0FE54AD94906:14B00F234FF120B5]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:962) at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:933) at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:909) 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:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) 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:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication Error Message: found:2[index.20180326155414001, index.20180326155414574, index.properties, replication.properties, snapshot_metadata] Stack Trace: java.lang.AssertionError: found:2[index.20180326155414001, index.20180326155414574, index.properties, replication.properties, snapshot_metadata] at __randomizedtesting.SeedInfo.seed([CF1B0FE54AD94906:14B00F234FF120B5]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:962) at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:933) at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:909) 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:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) 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:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication Error Message: found:2[index.20180326155411808, index.20180326155413209, index.properties, replication.properties, snapshot_metadata] Stack Trace: java.lang.AssertionError: found:2[index.20180326155411808, index.20180326155413209, index.properties, replication.properties, snapshot_metadata] at __randomizedtesting.SeedInfo.seed([CF1B0FE54AD94906:14B00F234FF120B5]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:962) at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:933) at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:909) 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:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) 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:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication Error Message: found:2[index.20180326155536519, index.20180326155537706, index.properties, replication.properties, snapshot_metadata] Stack Trace: java.lang.AssertionError: found:2[index.20180326155536519, index.20180326155537706, index.properties, replication.properties, snapshot_metadata] at __randomizedtesting.SeedInfo.seed([CF1B0FE54AD94906:14B00F234FF120B5]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:962) at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:933) at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:909) 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:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) 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:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication Error Message: found:2[index.20180326145806369, index.20180326145806617, index.properties, replication.properties, snapshot_metadata] Stack Trace: java.lang.AssertionError: found:2[index.20180326145806369, index.20180326145806617, index.properties, replication.properties, snapshot_metadata] at __randomizedtesting.SeedInfo.seed([CF1B0FE54AD94906:14B00F234FF120B5]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:962) at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:933) at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:909) 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:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) 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:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexFetchOnMasterRestart Error Message: expected:<1> but was:<2> Stack Trace: java.lang.AssertionError: expected:<1> but was:<2> at __randomizedtesting.SeedInfo.seed([CF1B0FE54AD94906:17ECCB01E1028B5A]: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.handler.TestReplicationHandler.doTestIndexFetchOnMasterRestart(TestReplicationHandler.java:664) 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:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) 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:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 1851 lines...] [junit4] JVM J1: stdout was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\temp\junit4-J1-20180326_211048_3253722864925057451071.sysout [junit4] >>> JVM J1 emitted unexpected output (verbatim) ---- [junit4] codec: HighCompressionCompressingStoredFields, pf: Lucene50, dvf: Asserting [junit4] <<< JVM J1: EOF ---- [...truncated 11699 lines...] [junit4] Suite: org.apache.solr.handler.TestReplicationHandler [junit4] 2> 1584682 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=614851018 [junit4] 2> 1584682 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1372020019 [junit4] 2> 1584682 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=678234957 [junit4] 2> 1584682 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1833668960 [junit4] 2> 1584682 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1726715570 [junit4] 2> 1584682 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=229532057 [junit4] 2> 1584682 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1297687554 [junit4] 2> 1584682 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=2083028056 [junit4] 2> 1584682 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1187789673 [junit4] 2> 1584682 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=948975744 [junit4] 2> 1584683 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1013801636 [junit4] 2> 1584683 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=51909581 [junit4] 2> 1584683 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=606627764 [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_CF1B0FE54AD94906-001\init-core-data-001 [junit4] 2> 1584698 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T11:27:37-10:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1584699 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1584699 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1584699 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 1584699 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5ad6459e{/solr,null,AVAILABLE} [junit4] 2> 1584700 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1c4267a4{HTTP/1.1,[http/1.1]}{127.0.0.1:59697} [junit4] 2> 1584700 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.e.j.s.Server Started @1584759ms [junit4] 2> 1584700 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_CF1B0FE54AD94906-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=59697} [junit4] 2> 1584700 ERROR (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1584700 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1584700 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 1584700 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 1584700 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1584700 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-26T21:57:16.101Z [junit4] 2> 1584701 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_CF1B0FE54AD94906-001\solr-instance-001\solr.xml [junit4] 2> 1584713 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 1584713 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 1584714 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@3a161180, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 1584790 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a161180 [junit4] 2> 1584801 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a161180 [junit4] 2> 1584801 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a161180 [junit4] 2> 1584807 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_CF1B0FE54AD94906-001\solr-instance-001\. [junit4] 2> 1584807 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 1584810 INFO (coreLoadExecutor-6480-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 1584834 INFO (coreLoadExecutor-6480-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1584842 INFO (coreLoadExecutor-6480-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1584845 INFO (coreLoadExecutor-6480-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 1584845 INFO (coreLoadExecutor-6480-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_CF1B0FE54AD94906-001\solr-instance-001\.\collection1, trusted=true [junit4] 2> 1584846 INFO (coreLoadExecutor-6480-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a161180 [junit4] 2> 1584846 INFO (coreLoadExecutor-6480-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1584846 INFO (coreLoadExecutor-6480-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_CF1B0FE54AD94906-001\solr-instance-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_CF1B0FE54AD94906-001\solr-instance-001\.\collection1\data\] [junit4] 2> 1584848 INFO (coreLoadExecutor-6480-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7620501481871389] [junit4] 2> 1584908 INFO (coreLoadExecutor-6480-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1584908 INFO (coreLoadExecutor-6480-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1584909 INFO (coreLoadExecutor-6480-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=61.2470703125, floorSegmentMB=1.9697265625, forceMergeDeletesPctAllowed=29.658570739645405, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3929456355656068 [junit4] 2> 1584909 INFO (coreLoadExecutor-6480-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7b86a4b2[collection1] main] [junit4] 2> 1584909 INFO (coreLoadExecutor-6480-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_CF1B0FE54AD94906-001\solr-instance-001\collection1\conf [junit4] 2> 1584910 INFO (coreLoadExecutor-6480-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl [junit4] 2> 1584910 INFO (coreLoadExecutor-6480-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1584910 INFO (searcherExecutor-6481-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7b86a4b2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1584922 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T11:27:37-10:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1584922 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1584922 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1584922 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 1584923 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1c2f07db{/solr,null,AVAILABLE} [junit4] 2> 1584923 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5c2b51ab{HTTP/1.1,[http/1.1]}{127.0.0.1:59704} [junit4] 2> 1584923 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.e.j.s.Server Started @1584983ms [junit4] 2> 1584923 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_CF1B0FE54AD94906-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=59704} [junit4] 2> 1584924 ERROR (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1584924 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1584924 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 1584924 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 1584924 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1584924 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-26T21:57:16.325Z [junit4] 2> 1584924 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_CF1B0FE54AD94906-001\solr-instance-002\solr.xml [junit4] 2> 1584932 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 1584932 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 1584933 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@3a161180, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 1585004 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a161180 [junit4] 2> 1585011 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a161180 [junit4] 2> 1585011 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a161180 [junit4] 2> 1585016 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_CF1B0FE54AD94906-001\solr-instance-002\. [junit4] 2> 1585016 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 1585017 INFO (coreLoadExecutor-6490-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 1585041 INFO (coreLoadExecutor-6490-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1585048 INFO (coreLoadExecutor-6490-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1585051 INFO (coreLoadExecutor-6490-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 1585051 INFO (coreLoadExecutor-6490-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_CF1B0FE54AD94906-001\solr-instance-002\.\collection1, trusted=true [junit4] 2> 1585052 INFO (coreLoadExecutor-6490-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a161180 [junit4] 2> 1585052 INFO (coreLoadExecutor-6490-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1585052 INFO (coreLoadExecutor-6490-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_CF1B0FE54AD94906-001\solr-instance-002\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_CF1B0FE54AD94906-001\solr-instance-002\.\collection1\data\] [junit4] 2> 1585053 INFO (coreLoadExecutor-6490-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7620501481871389] [junit4] 2> 1585117 INFO (coreLoadExecutor-6490-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1585117 INFO (coreLoadExecutor-6490-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1585117 INFO (coreLoadExecutor-6490-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=61.2470703125, floorSegmentMB=1.9697265625, forceMergeDeletesPctAllowed=29.658570739645405, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3929456355656068 [junit4] 2> 1585118 INFO (coreLoadExecutor-6490-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4ad56633[collection1] main] [junit4] 2> 1585118 INFO (coreLoadExecutor-6490-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_CF1B0FE54AD94906-001\solr-instance-002\collection1\conf [junit4] 2> 1585121 INFO (coreLoadExecutor-6490-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 1585121 INFO (coreLoadExecutor-6490-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1585122 INFO (searcherExecutor-6491-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4ad56633[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1585124 INFO (qtp1746974623-20088) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 1585124 INFO (qtp1746974623-20092) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 1585124 INFO (indexFetcher-6495-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 1585125 INFO (indexFetcher-6495-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 1585125 INFO (indexFetcher-6495-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 1585125 INFO (indexFetcher-6495-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 1585125 INFO (indexFetcher-6495-thread-1) [ ] o.a.s.h.IndexFetcher New index in Master. Deleting mine... [junit4] 2> 1585125 INFO (indexFetcher-6495-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1585125 INFO (indexFetcher-6495-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3eb957c0 commitCommandVersion:0 [junit4] 2> 1585126 INFO (indexFetcher-6495-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3a4296f3[collection1] main] [junit4] 2> 1585126 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0 [junit4] 2> 1585126 INFO (indexFetcher-6495-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1585127 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1585127 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@504ddb38 commitCommandVersion:0 [junit4] 2> 1585127 INFO (searcherExecutor-6491-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3a4296f3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1585129 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@46846b5b[collection1] main] [junit4] 2> 1585129 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1585130 INFO (searcherExecutor-6481-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@46846b5b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1)))} [junit4] 2> 1585130 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 4 [junit4] 2> 1585151 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 1585152 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 1585152 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 1585152 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's version: 1522101436528 [junit4] 2> 1585152 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 1585152 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's version: 1522101436526 [junit4] 2> 1585152 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 1585158 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=5 [junit4] 2> 1585158 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 9 [junit4] 2> 1585158 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=true) to MockDirectoryWrapper(RAMDirectory@5f522a6e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f12a484) [junit4] 2> 1585159 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1585160 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_FST50_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1585161 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1585161 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1585162 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_FST50_0.tfp&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1585162 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1585163 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1585164 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1585164 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher File segments_2 did not match. expected checksum is 2003189949 and actual is checksum 2261238384. expected length is 182 and actual length is 117 [junit4] 2> 1585164 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1585165 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Bytes downloaded: 1510, Bytes skipped downloading: 0 [junit4] 2> 1585165 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=true,bytesDownloaded=1510) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@5f522a6e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f12a484) [junit4] 2> 1585166 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrCore Updating index properties... index=index.20180326145716559 [junit4] 2> 1585166 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher removing old index directory MockDirectoryWrapper(RAMDirectory@570f0b52 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13b8b240) [junit4] 2> 1585167 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1585167 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1585167 INFO (explicit-fetchindex-cmd) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4a9c2324[collection1] main] [junit4] 2> 1585169 INFO (searcherExecutor-6491-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4a9c2324[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1)))} [junit4] 2> 1585169 INFO (qtp744607838-20114) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:59697/solr/collection1/replication&command=fetchindex} status=0 QTime=19 [junit4] 2> 1585179 INFO (qtp744607838-20111) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty1&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 1585179 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs [junit4] 2> 1585179 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 1585179 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 1585181 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 1585181 INFO (qtp744607838-20109) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=1 [junit4] 2> 1585181 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 1585181 INFO (qtp744607838-20111) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 1585182 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1585182 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 1585182 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1585182 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 0 [junit4] 2> 1585183 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 1585185 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 1585185 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 1585185 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's version: 1522101436528 [junit4] 2> 1585185 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 1585185 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's version: 1522101436528 [junit4] 2> 1585185 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave in sync with master. [junit4] 2> 1585185 INFO (qtp744607838-20109) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:59697/solr/collection1/replication&command=fetchindex} status=0 QTime=2 [junit4] 2> 1585187 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0 [junit4] 2> 1585187 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1585187 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@504ddb38 commitCommandVersion:0 [junit4] 2> 1585188 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@35d0f4bb[collection1] main] [junit4] 2> 1585189 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1585190 INFO (searcherExecutor-6481-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@35d0f4bb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1) Uninverting(_1(8.0.0):C1)))} [junit4] 2> 1585190 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 2 [junit4] 2> 1585191 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 1585193 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 1585193 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 3 [junit4] 2> 1585193 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's version: 1522101436588 [junit4] 2> 1585193 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 1585193 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's version: 1522101436528 [junit4] 2> 1585193 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 1585194 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=0 [junit4] 2> 1585194 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 17 [junit4] 2> 1585194 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1585195 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1585195 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@593e68cc lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cde08f1) [junit4] 2> 1585195 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 1585195 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_FST50_0.doc because it already exists [junit4] 2> 1585195 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists [junit4] 2> 1585195 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists [junit4] 2> 1585195 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_FST50_0.tfp because it already exists [junit4] 2> 1585195 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists [junit4] 2> 1585195 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists [junit4] 2> 1585195 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists [junit4] 2> 1585195 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1585196 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1585197 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_FST50_0.tfp&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1585198 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1585199 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1585208 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1585209 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_FST50_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1585209 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1585210 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=segments_3&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1585210 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Bytes downloaded: 1572, Bytes skipped downloading: 0 [junit4] 2> 1585210 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1572) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@593e68cc lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cde08f1) [junit4] 2> 1585212 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 1585213 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1585213 INFO (explicit-fetchindex-cmd) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5f852127[collection1] main] [junit4] 2> 1585214 INFO (searcherExecutor-6491-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5f852127[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1) Uninverting(_1(8.0.0):C1)))} [junit4] 2> 1585215 INFO (qtp744607838-20111) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:59697/solr/collection1/replication&command=fetchindex} status=0 QTime=23 [junit4] 2> 1585215 INFO (qtp744607838-20109) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty2&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 1585216 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs [junit4] 2> 1585216 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 1585217 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 1585217 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 1585218 INFO (qtp744607838-20111) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 1585218 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 1585218 INFO (qtp744607838-20109) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 1585219 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0 [junit4] 2> 1585219 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1585219 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@504ddb38 commitCommandVersion:0 [junit4] 2> 1585220 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@59f1e002[collection1] realtime] [junit4] 2> 1585220 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1585220 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={openSearcher=false&commit=true&wt=javabin&version=2}{commit=} 0 1 [junit4] 2> 1585222 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 1585223 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 1585223 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 4 [junit4] 2> 1585223 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's version: 1522101436620 [junit4] 2> 1585223 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 3 [junit4] 2> 1585223 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's version: 1522101436588 [junit4] 2> 1585223 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 1585224 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=0 [junit4] 2> 1585224 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 25 [junit4] 2> 1585225 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1585225 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1585225 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 1585225 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 1586226 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 1586226 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 1587227 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 1587227 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 1588227 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 1588227 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 1589228 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 1589228 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 1590228 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 1590228 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 1591229 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 1591229 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 1592229 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 1592229 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 1593230 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 1593230 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 1594231 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 1594231 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 1595232 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 1595232 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 1596233 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher IndexFetcher slept for 11000ms for unused lucene index files to be delete-able [junit4] 2> 1596233 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@fb4f1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a1f9813) [junit4] 2> 1596233 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 1596233 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_FST50_0.doc because it already exists [junit4] 2> 1596233 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists [junit4] 2> 1596233 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists [junit4] 2> 1596233 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_FST50_0.tfp because it already exists [junit4] 2> 1596233 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists [junit4] 2> 1596233 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists [junit4] 2> 1596233 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists [junit4] 2> 1596233 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists [junit4] 2> 1596233 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.nvd because it already exists [junit4] 2> 1596233 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1_FST50_0.tfp because it already exists [junit4] 2> 1596233 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.fdx because it already exists [junit4] 2> 1596233 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.fnm because it already exists [junit4] 2> 1596233 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.nvm because it already exists [junit4] 2> 1596233 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1_FST50_0.doc because it already exists [junit4] 2> 1596233 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _1.fdt because it already exists [junit4] 2> 1596235 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1596235 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1596236 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1596237 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_FST50_0.tfp&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1596237 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1596238 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1596238 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1596239 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_FST50_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1596239 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=segments_4&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 1596241 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Bytes downloaded: 1634, Bytes skipped downloading: 0 [junit4] 2> 1596241 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1634) : 11 secs (148 bytes/sec) to MockDirectoryWrapper(RAMDirectory@fb4f1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a1f9813) [junit4] 2> 1596242 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 1596243 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1596243 INFO (explicit-fetchindex-cmd) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7da8760a[collection1] main] [junit4] 2> 1596243 INFO (searcherExecutor-6491-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7da8760a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1) Uninverting(_1(8.0.0):C1) Uninverting(_2(8.0.0):C1)))} [junit4] 2> 1596246 INFO (qtp744607838-20111) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:59697/solr/collection1/replication&command=fetchindex} status=0 QTime=11024 [junit4] 2> 1596252 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 1596252 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs [junit4] 2> 1596254 INFO (qtp744607838-20109) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 1596254 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[CF1B0FE54AD94906]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs [junit4] 2> 1596255 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0 [junit4] 2> 1596255 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1596255 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@504ddb38 commitCommandVersion:0 [junit4] 2> 1596255 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@c8e1717[collection1] main] [junit4] 2> 1596256 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1596257 INFO (searcherExecutor-6481-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@c8e1717[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1) Uninverting(_1(8.0.0):C1) Uninverting(_2(8.0.0):C1) Uninverting(_3(8.0.0):C1)))} [junit4] 2> 1596257 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 2 [junit4] 2> 1596258 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 1596259 INFO (qtp1746974623-20090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 1596259 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 5 [junit4] 2> 1596260 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's version: 1522101447656 [junit4] 2> 1596260 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 4 [junit4] 2> 1596260 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's version: 1522101436620 [junit4] 2> 1596260 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 1596261 INFO (qtp1746974623-20093) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=0 [junit4] 2> 1596261 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 33 [junit4] 2> 1596261 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1596262 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 1596262 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@3c1ea263 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@26775812) [junit4] 2> 1596262 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 1596262 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_FST50_0.doc because [...truncated too long message...] tReplicationHandler.doTestStressReplication-seed#[CF1B0FE54AD94906]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 274378 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[CF1B0FE54AD94906]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@51048e25{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 274379 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[CF1B0FE54AD94906]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=899518260 [junit4] 2> 274381 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[CF1B0FE54AD94906]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 274381 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[CF1B0FE54AD94906]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@536779c5: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@cade4d1 [junit4] 2> 274382 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[CF1B0FE54AD94906]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 274382 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[CF1B0FE54AD94906]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@669229c5: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@76a5f937 [junit4] 2> 274383 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[CF1B0FE54AD94906]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 274383 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[CF1B0FE54AD94906]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6e728d27: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@7d8b6f4e [junit4] 2> 274386 INFO (coreCloseExecutor-1685-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@15ddb250 [junit4] 2> 274386 INFO (coreCloseExecutor-1685-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=366850640 [junit4] 2> 274386 INFO (coreCloseExecutor-1685-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@75f05801: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1c858f8f [junit4] 2> 274418 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[CF1B0FE54AD94906]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6fbf9618{/solr,null,UNAVAILABLE} [junit4] 2> 274418 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[CF1B0FE54AD94906]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestReplicationHandler -Dtests.method=doTestStressReplication -Dtests.seed=CF1B0FE54AD94906 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=cs -Dtests.timezone=MST -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] FAILURE 7.21s J1 | TestReplicationHandler.doTestStressReplication <<< [junit4] > Throwable #1: java.lang.AssertionError: found:2[index.20180326155536519, index.20180326155537706, index.properties, replication.properties, snapshot_metadata] [junit4] > at __randomizedtesting.SeedInfo.seed([CF1B0FE54AD94906:14B00F234FF120B5]:0) [junit4] > at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:962) [junit4] > at org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:933) [junit4] > at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:909) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] 2> 274439 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ ] o.a.s.c.DirectoryFactory Found 1 old index directories to clean-up under C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_CF1B0FE54AD94906-003\solr-instance-013\.\collection1\data\ afterReload=false [junit4] 2> 274445 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ ] o.a.s.c.DirectoryFactory Deleted old index directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_CF1B0FE54AD94906-003\solr-instance-013\.\collection1\data\index.20180326155536519 [junit4] IGNOR/A 0.02s J1 | TestReplicationHandler.doTestIndexAndConfigReplication [junit4] > Assumption #1: Ignored, failures limit reached (5 >= 5). [junit4] IGNOR/A 0.01s J1 | TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity [junit4] > Assumption #1: Ignored, failures limit reached (5 >= 5). [junit4] IGNOR/A 0.00s J1 | TestReplicationHandler.doTestRepeater [junit4] > Assumption #1: Ignored, failures limit reached (5 >= 5). [junit4] IGNOR/A 0.01s J1 | TestReplicationHandler.doTestIndexAndConfigAliasReplication [junit4] > Assumption #1: Ignored, failures limit reached (5 >= 5). [junit4] IGNOR/A 0.00s J1 | TestReplicationHandler.doTestHandlerPathUnchanged [junit4] > Assumption #1: Ignored, failures limit reached (5 >= 5). [junit4] IGNOR/A 0.01s J1 | TestReplicationHandler.doTestReplicateAfterStartup [junit4] > Assumption #1: Ignored, failures limit reached (5 >= 5). [junit4] IGNOR/A 0.00s J1 | TestReplicationHandler.doTestIndexFetchOnMasterRestart [junit4] > Assumption #1: Ignored, failures limit reached (5 >= 5). [junit4] IGNOR/A 0.00s J1 | TestReplicationHandler.doTestReplicateAfterWrite2Slave [junit4] > Assumption #1: Ignored, failures limit reached (5 >= 5). [junit4] IGNOR/A 0.01s J1 | TestReplicationHandler.doTestReplicateAfterCoreReload [junit4] > Assumption #1: Ignored, failures limit reached (5 >= 5). [junit4] IGNOR/A 0.00s J1 | TestReplicationHandler.doTestIllegalFilePaths [junit4] > Assumption #1: Ignored, failures limit reached (5 >= 5). [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_CF1B0FE54AD94906-003 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {name=FST50, id=FST50}, docValues:{}, maxPointsInLeafNode=1027, maxMBSortInHeap=6.528832723634499, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@e9e0652), locale=cs, timezone=MST [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=48505512,total=101531648 [junit4] 2> NOTE: All tests run in this JVM: [TestReplicationHandler, TestReplicationHandler, TestReplicationHandler] [junit4] Completed [5/5 (5!)] on J1 in 56.81s, 16 tests, 1 failure, 10 skipped <<< FAILURES! [...truncated 16 lines...] BUILD FAILED C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1568: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1092: There were test failures: 5 suites, 80 tests, 7 failures, 10 ignored (10 assumptions) [seed: CF1B0FE54AD94906] Total time: 4 minutes 37 seconds [repro] Setting last failure code to 1 [repro] Failures: [repro] 5/5 failed: org.apache.solr.handler.TestReplicationHandler [repro] Exiting with code 1 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build move /y lucene\build lucene\build.repro 1 dir(s) moved. C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build move /y solr\build solr\build.repro 1 dir(s) moved. C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build.orig move /y lucene\build.orig lucene\build 1 dir(s) moved. C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build.orig move /y solr\build.orig solr\build 1 dir(s) moved. C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>exit 0 Archiving artifacts Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 [WARNINGS] Parsing warnings in console log with parser Java Compiler (javac) Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 <Git Blamer> Using GitBlamer to create author and commit information for all warnings. <Git Blamer> GIT_COMMIT=05dca0493db254005e96997ef1e7c65082620ba7, workspace=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows [WARNINGS] Computing warning deltas based on reference build #7241 Recording test results Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 Build step 'Publish JUnit test result report' changed build result to UNSTABLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures) Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org