You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/12/17 12:05:27 UTC
[JENKINS] Lucene-Solr-7.x-MacOSX (64bit/jdk1.8.0) - Build # 345 -
Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/345/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC
5 tests failed.
FAILED: org.apache.solr.cloud.CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls
Error Message:
org/apache/solr/client/solrj/request/CollectionAdminRequest$SplitShard
Stack Trace:
java.lang.NoClassDefFoundError: org/apache/solr/client/solrj/request/CollectionAdminRequest$SplitShard
at __randomizedtesting.SeedInfo.seed([D79C8EF0E1F5DC6A:8FF80291E79F74BE]:0)
at org.apache.solr.client.solrj.request.CollectionAdminRequest.splitShard(CollectionAdminRequest.java:1060)
at org.apache.solr.cloud.CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls(CollectionsAPIAsyncDistributedZkTest.java:67)
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.cloud.CustomCollectionTest.testCustomCollectionsAPI
Error Message:
Could not find collection : implicitcoll
Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : implicitcoll
at __randomizedtesting.SeedInfo.seed([D79C8EF0E1F5DC6A:BD7D009BDC6F6A12]:0)
at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:118)
at org.apache.solr.cloud.SolrCloudTestCase.getCollectionState(SolrCloudTestCase.java:247)
at org.apache.solr.cloud.CustomCollectionTest.testCustomCollectionsAPI(CustomCollectionTest.java:68)
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.cloud.MultiThreadedOCPTest.test
Error Message:
org/apache/solr/client/solrj/request/CollectionAdminRequest$SplitShard
Stack Trace:
java.lang.NoClassDefFoundError: org/apache/solr/client/solrj/request/CollectionAdminRequest$SplitShard
at __randomizedtesting.SeedInfo.seed([D79C8EF0E1F5DC6A:5FC8B12A4F09B192]:0)
at org.apache.solr.client.solrj.request.CollectionAdminRequest.splitShard(CollectionAdminRequest.java:1060)
at org.apache.solr.cloud.MultiThreadedOCPTest.testDeduplicationOfSubmittedTasks(MultiThreadedOCPTest.java:212)
at org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:65)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
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)
Caused by: java.lang.ClassNotFoundException: org.apache.solr.client.solrj.request.CollectionAdminRequest$SplitShard
at java.net.URLClassLoader$1.run(URLClassLoader.java:370)
at java.net.URLClassLoader$1.run(URLClassLoader.java:362)
at java.security.AccessController.doPrivileged(Native Method)
at java.net.URLClassLoader.findClass(URLClassLoader.java:361)
at java.lang.ClassLoader.loadClass(ClassLoader.java:424)
at sun.misc.Launcher$AppClassLoader.loadClass(Launcher.java:335)
at java.lang.ClassLoader.loadClass(ClassLoader.java:357)
... 44 more
Caused by: java.io.FileNotFoundException: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-solrj/classes/java/org/apache/solr/client/solrj/request/CollectionAdminRequest$SplitShard.class (Too many open files)
at java.io.FileInputStream.open0(Native Method)
at java.io.FileInputStream.open(FileInputStream.java:195)
at java.io.FileInputStream.<init>(FileInputStream.java:138)
at sun.misc.URLClassPath$FileLoader$1.getInputStream(URLClassPath.java:1288)
at sun.misc.Resource.cachedInputStream(Resource.java:77)
at sun.misc.Resource.getByteBuffer(Resource.java:160)
at java.net.URLClassLoader.defineClass(URLClassLoader.java:454)
at java.net.URLClassLoader.access$100(URLClassLoader.java:73)
at java.net.URLClassLoader$1.run(URLClassLoader.java:368)
... 50 more
FAILED: org.apache.solr.cloud.autoscaling.TestPolicyCloud.testCreateCollectionSplitShard
Error Message:
org/apache/solr/client/solrj/request/CollectionAdminRequest$SplitShard
Stack Trace:
java.lang.NoClassDefFoundError: org/apache/solr/client/solrj/request/CollectionAdminRequest$SplitShard
at __randomizedtesting.SeedInfo.seed([D79C8EF0E1F5DC6A:212FD0B51BF5DD4]:0)
at org.apache.solr.client.solrj.request.CollectionAdminRequest.splitShard(CollectionAdminRequest.java:1060)
at org.apache.solr.cloud.autoscaling.TestPolicyCloud.testCreateCollectionSplitShard(TestPolicyCloud.java:173)
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 org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:110)
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.cloud.autoscaling.sim.TestPolicyCloud.testCreateCollectionSplitShard
Error Message:
org/apache/solr/client/solrj/request/CollectionAdminRequest$SplitShard
Stack Trace:
java.lang.NoClassDefFoundError: org/apache/solr/client/solrj/request/CollectionAdminRequest$SplitShard
at __randomizedtesting.SeedInfo.seed([D79C8EF0E1F5DC6A:212FD0B51BF5DD4]:0)
at org.apache.solr.client.solrj.request.CollectionAdminRequest.splitShard(CollectionAdminRequest.java:1060)
at org.apache.solr.cloud.autoscaling.sim.TestPolicyCloud.testCreateCollectionSplitShard(TestPolicyCloud.java:162)
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 org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:110)
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 12155 lines...]
[junit4] Suite: org.apache.solr.cloud.MultiThreadedOCPTest
[junit4] 2> 765667 INFO (SUITE-MultiThreadedOCPTest-seed#[D79C8EF0E1F5DC6A]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/init-core-data-001
[junit4] 2> 765667 INFO (SUITE-MultiThreadedOCPTest-seed#[D79C8EF0E1F5DC6A]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 765671 INFO (SUITE-MultiThreadedOCPTest-seed#[D79C8EF0E1F5DC6A]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 765671 INFO (SUITE-MultiThreadedOCPTest-seed#[D79C8EF0E1F5DC6A]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 765672 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 765672 INFO (Thread-1396) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 765672 INFO (Thread-1396) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 765675 ERROR (Thread-1396) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 765782 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.ZkTestServer start zk server on port:56896
[junit4] 2> 765808 INFO (zkConnectionManagerCallback-1672-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 765817 INFO (zkConnectionManagerCallback-1674-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 765831 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 765836 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 765839 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 765841 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 765844 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 765846 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 765848 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 765850 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 765852 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 765853 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 765855 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 765857 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 766250 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 766251 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3bd26c5{/,null,AVAILABLE}
[junit4] 2> 766251 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2537dfc9{HTTP/1.1,[http/1.1]}{127.0.0.1:56899}
[junit4] 2> 766252 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.e.j.s.Server Started @769455ms
[junit4] 2> 766252 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/tempDir-001/control/data, hostContext=/, hostPort=56899, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/control-001/cores}
[junit4] 2> 766252 ERROR (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 766252 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 766252 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 766252 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 766252 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-17T10:56:38.705Z
[junit4] 2> 766254 INFO (zkConnectionManagerCallback-1676-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 766255 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 766255 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/control-001/solr.xml
[junit4] 2> 766259 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 766259 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 766260 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 766263 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56896/solr
[junit4] 2> 766265 INFO (zkConnectionManagerCallback-1680-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 766267 INFO (zkConnectionManagerCallback-1682-thread-1-processing-n:127.0.0.1:56899_) [n:127.0.0.1:56899_ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 766398 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56899_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 766399 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56899_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:56899_
[junit4] 2> 766401 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56899_ ] o.a.s.c.Overseer Overseer (id=99189273272451076-127.0.0.1:56899_-n_0000000000) starting
[junit4] 2> 766410 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56899_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56899_
[junit4] 2> 766411 INFO (OverseerStateUpdate-99189273272451076-127.0.0.1:56899_-n_0000000000) [n:127.0.0.1:56899_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 766464 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56899_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0
[junit4] 2> 766470 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56899_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0
[junit4] 2> 766470 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56899_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0
[junit4] 2> 766471 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56899_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/control-001/cores
[junit4] 2> 766491 INFO (zkConnectionManagerCallback-1688-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 766492 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 766494 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56896/solr ready
[junit4] 2> 766496 INFO (qtp376727992-6792) [n:127.0.0.1:56899_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:56899_&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 766499 INFO (OverseerThreadFactory-2813-thread-1-processing-n:127.0.0.1:56899_) [n:127.0.0.1:56899_ ] o.a.s.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 766620 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 766621 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 766735 INFO (zkCallback-1681-thread-1-processing-n:127.0.0.1:56899_) [n:127.0.0.1:56899_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 767671 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 767692 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 767776 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 767797 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from collection control_collection, trusted=true
[junit4] 2> 767797 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0
[junit4] 2> 767797 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 767797 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 767799 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=49, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 767801 WARN (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 767843 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 767843 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 767844 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 767844 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 767845 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.535874684911638]
[junit4] 2> 767845 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@513f276b[control_collection_shard1_replica_n1] main]
[junit4] 2> 767847 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 767847 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 767847 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 767847 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1587028374637772800
[junit4] 2> 767848 INFO (searcherExecutor-2816-thread-1-processing-n:127.0.0.1:56899_ x:control_collection_shard1_replica_n1 s:shard1 c:control_collection) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@513f276b[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 767853 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 767853 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 767853 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:56899/control_collection_shard1_replica_n1/
[junit4] 2> 767853 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 767853 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:56899/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 767853 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 767858 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:56899/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 767962 INFO (zkCallback-1681-thread-2-processing-n:127.0.0.1:56899_) [n:127.0.0.1:56899_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 767979 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 767984 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1363
[junit4] 2> 767991 INFO (qtp376727992-6792) [n:127.0.0.1:56899_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 768095 INFO (zkCallback-1681-thread-2-processing-n:127.0.0.1:56899_) [n:127.0.0.1:56899_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 768512 INFO (OverseerCollectionConfigSetProcessor-99189273272451076-127.0.0.1:56899_-n_0000000000) [n:127.0.0.1:56899_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 768995 INFO (qtp376727992-6792) [n:127.0.0.1:56899_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:56899_&wt=javabin&version=2} status=0 QTime=2499
[junit4] 2> 769009 INFO (zkConnectionManagerCallback-1692-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 769012 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 769016 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56896/solr ready
[junit4] 2> 769016 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 769018 INFO (qtp376727992-6790) [n:127.0.0.1:56899_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 769023 INFO (OverseerThreadFactory-2813-thread-2-processing-n:127.0.0.1:56899_) [n:127.0.0.1:56899_ ] o.a.s.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 769025 WARN (OverseerThreadFactory-2813-thread-2-processing-n:127.0.0.1:56899_) [n:127.0.0.1:56899_ ] o.a.s.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 769232 INFO (qtp376727992-6790) [n:127.0.0.1:56899_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 769232 INFO (qtp376727992-6790) [n:127.0.0.1:56899_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2} status=0 QTime=214
[junit4] 2> 769510 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/shard-1-001 of type NRT
[junit4] 2> 769511 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 769512 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4a8cd7a6{/,null,AVAILABLE}
[junit4] 2> 769512 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4d94e89a{HTTP/1.1,[http/1.1]}{127.0.0.1:56909}
[junit4] 2> 769512 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.e.j.s.Server Started @772715ms
[junit4] 2> 769512 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/tempDir-001/jetty1, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=56909, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/shard-1-001/cores}
[junit4] 2> 769513 ERROR (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 769513 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 769513 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 769513 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 769513 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-17T10:56:41.966Z
[junit4] 2> 769515 INFO (zkConnectionManagerCallback-1694-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 769516 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 769516 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/shard-1-001/solr.xml
[junit4] 2> 769520 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 769520 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 769521 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 769524 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56896/solr
[junit4] 2> 769525 INFO (zkConnectionManagerCallback-1698-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 769527 INFO (zkConnectionManagerCallback-1700-thread-1-processing-n:127.0.0.1:56909_) [n:127.0.0.1:56909_ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 769537 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56909_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 769541 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56909_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 769543 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56909_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56909_
[junit4] 2> 769545 INFO (zkCallback-1681-thread-2-processing-n:127.0.0.1:56899_) [n:127.0.0.1:56899_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 769545 INFO (zkCallback-1699-thread-1-processing-n:127.0.0.1:56909_) [n:127.0.0.1:56909_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 769545 INFO (zkCallback-1691-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 769631 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56909_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0
[junit4] 2> 769638 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56909_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0
[junit4] 2> 769638 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56909_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0
[junit4] 2> 769640 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56909_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/shard-1-001/cores
[junit4] 2> 769665 INFO (qtp376727992-6793) [n:127.0.0.1:56899_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:56909_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 769668 INFO (OverseerCollectionConfigSetProcessor-99189273272451076-127.0.0.1:56899_-n_0000000000) [n:127.0.0.1:56899_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 769668 INFO (OverseerThreadFactory-2813-thread-3-processing-n:127.0.0.1:56899_) [n:127.0.0.1:56899_ ] o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:56909_ for creating new replica
[junit4] 2> 769673 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n41&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 769674 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 770705 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 770729 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.IndexSchema [collection1_shard2_replica_n41] Schema name=test
[junit4] 2> 770842 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 770873 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n41' using configuration from collection collection1, trusted=true
[junit4] 2> 770873 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard2.replica_n41' (registry 'solr.core.collection1.shard2.replica_n41') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0
[junit4] 2> 770874 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 770874 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore [[collection1_shard2_replica_n41] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/shard-1-001/cores/collection1_shard2_replica_n41], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/shard-1-001/cores/collection1_shard2_replica_n41/data/]
[junit4] 2> 770876 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=49, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 770878 WARN (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 770940 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 770940 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 770941 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 770942 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 770943 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.535874684911638]
[junit4] 2> 770943 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.SolrIndexSearcher Opening [Searcher@6e08d800[collection1_shard2_replica_n41] main]
[junit4] 2> 770945 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 770945 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 770946 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 770946 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1587028377887309824
[junit4] 2> 770947 INFO (searcherExecutor-2827-thread-1-processing-n:127.0.0.1:56909_ x:collection1_shard2_replica_n41 s:shard2 c:collection1) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore [collection1_shard2_replica_n41] Registered new searcher Searcher@6e08d800[collection1_shard2_replica_n41] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 770953 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 770953 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 770953 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:56909/collection1_shard2_replica_n41/
[junit4] 2> 770953 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 770953 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy http://127.0.0.1:56909/collection1_shard2_replica_n41/ has no replicas
[junit4] 2> 770953 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 770958 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:56909/collection1_shard2_replica_n41/ shard2
[junit4] 2> 771127 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 771130 INFO (qtp2008762350-6841) [n:127.0.0.1:56909_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n41&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1456
[junit4] 2> 771133 INFO (qtp376727992-6793) [n:127.0.0.1:56899_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:56909_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2} status=0 QTime=1467
[junit4] 2> 771521 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/shard-2-001 of type NRT
[junit4] 2> 771522 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 771524 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2e5bd2ad{/,null,AVAILABLE}
[junit4] 2> 771524 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6a744931{HTTP/1.1,[http/1.1]}{127.0.0.1:56914}
[junit4] 2> 771524 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.e.j.s.Server Started @774727ms
[junit4] 2> 771524 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/tempDir-001/jetty2, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=56914, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/shard-2-001/cores}
[junit4] 2> 771525 ERROR (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 771526 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 771526 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 771526 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 771526 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-17T10:56:43.979Z
[junit4] 2> 771529 INFO (zkConnectionManagerCallback-1704-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 771531 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 771531 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/shard-2-001/solr.xml
[junit4] 2> 771537 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 771537 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 771538 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 771543 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56896/solr
[junit4] 2> 771545 INFO (zkConnectionManagerCallback-1708-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 771548 INFO (zkConnectionManagerCallback-1710-thread-1-processing-n:127.0.0.1:56914_) [n:127.0.0.1:56914_ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 771561 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56914_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 771565 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56914_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 771569 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56914_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56914_
[junit4] 2> 771571 INFO (zkCallback-1681-thread-1-processing-n:127.0.0.1:56899_) [n:127.0.0.1:56899_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 771572 INFO (zkCallback-1691-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 771572 INFO (zkCallback-1699-thread-1-processing-n:127.0.0.1:56909_) [n:127.0.0.1:56909_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 771572 INFO (zkCallback-1709-thread-1-processing-n:127.0.0.1:56914_) [n:127.0.0.1:56914_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 771628 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56914_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0
[junit4] 2> 771636 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56914_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0
[junit4] 2> 771636 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56914_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0
[junit4] 2> 771637 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56914_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/shard-2-001/cores
[junit4] 2> 771672 INFO (OverseerCollectionConfigSetProcessor-99189273272451076-127.0.0.1:56899_-n_0000000000) [n:127.0.0.1:56899_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 771690 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:56914_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 771694 INFO (OverseerThreadFactory-2813-thread-4-processing-n:127.0.0.1:56899_) [n:127.0.0.1:56899_ ] o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:56914_ for creating new replica
[junit4] 2> 771700 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n43&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 771701 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 772733 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 772750 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.s.IndexSchema [collection1_shard1_replica_n43] Schema name=test
[junit4] 2> 772871 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 772898 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n43' using configuration from collection collection1, trusted=true
[junit4] 2> 772898 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard1.replica_n43' (registry 'solr.core.collection1.shard1.replica_n43') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0
[junit4] 2> 772898 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 772898 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrCore [[collection1_shard1_replica_n43] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/shard-2-001/cores/collection1_shard1_replica_n43], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/shard-2-001/cores/collection1_shard1_replica_n43/data/]
[junit4] 2> 772900 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=49, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 772903 WARN (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 772978 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 772978 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 772980 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 772980 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 772981 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.535874684911638]
[junit4] 2> 772982 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.s.SolrIndexSearcher Opening [Searcher@604eeb3a[collection1_shard1_replica_n43] main]
[junit4] 2> 772983 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 772984 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 772985 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 772985 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1587028380025356288
[junit4] 2> 772987 INFO (searcherExecutor-2838-thread-1-processing-n:127.0.0.1:56914_ x:collection1_shard1_replica_n43 s:shard1 c:collection1) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrCore [collection1_shard1_replica_n43] Registered new searcher Searcher@604eeb3a[collection1_shard1_replica_n43] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 772993 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 772993 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 772994 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:56914/collection1_shard1_replica_n43/
[junit4] 2> 772994 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 772994 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SyncStrategy http://127.0.0.1:56914/collection1_shard1_replica_n43/ has no replicas
[junit4] 2> 772994 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 773002 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:56914/collection1_shard1_replica_n43/ shard1
[junit4] 2> 773166 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 773169 INFO (qtp1317534622-6872) [n:127.0.0.1:56914_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n43&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1469
[junit4] 2> 773172 INFO (qtp376727992-6794) [n:127.0.0.1:56899_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:56914_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2} status=0 QTime=1482
[junit4] 2> 773569 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/shard-3-001 of type NRT
[junit4] 2> 773570 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 773572 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6e154410{/,null,AVAILABLE}
[junit4] 2> 773572 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4ffb86e1{HTTP/1.1,[http/1.1]}{127.0.0.1:56919}
[junit4] 2> 773572 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.e.j.s.Server Started @776775ms
[junit4] 2> 773572 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/tempDir-001/jetty3, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=56919, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/shard-3-001/cores}
[junit4] 2> 773573 ERROR (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 773574 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 773574 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 773574 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 773574 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-17T10:56:46.027Z
[junit4] 2> 773576 INFO (zkConnectionManagerCallback-1714-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 773578 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 773578 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/shard-3-001/solr.xml
[junit4] 2> 773585 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 773585 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 773586 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 773592 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56896/solr
[junit4] 2> 773594 INFO (zkConnectionManagerCallback-1718-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 773598 INFO (zkConnectionManagerCallback-1720-thread-1-processing-n:127.0.0.1:56919_) [n:127.0.0.1:56919_ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 773612 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56919_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 773619 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56919_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 773623 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56919_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56919_
[junit4] 2> 773625 INFO (zkCallback-1691-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 773625 INFO (zkCallback-1709-thread-1-processing-n:127.0.0.1:56914_) [n:127.0.0.1:56914_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 773626 INFO (zkCallback-1699-thread-1-processing-n:127.0.0.1:56909_) [n:127.0.0.1:56909_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 773626 INFO (zkCallback-1681-thread-2-processing-n:127.0.0.1:56899_) [n:127.0.0.1:56899_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 773626 INFO (zkCallback-1719-thread-1-processing-n:127.0.0.1:56919_) [n:127.0.0.1:56919_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 773696 INFO (OverseerCollectionConfigSetProcessor-99189273272451076-127.0.0.1:56899_-n_0000000000) [n:127.0.0.1:56899_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 773787 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56919_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0
[junit4] 2> 773792 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56919_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0
[junit4] 2> 773792 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56919_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0
[junit4] 2> 773797 INFO (TEST-MultiThreadedOCPTest.test-seed#[D79C8EF0E1F5DC6A]) [n:127.0.0.1:56919_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/shard-3-001/cores
[junit4] 2> 773861 INFO (qtp376727992-6792) [n:127.0.0.1:56899_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:56919_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 773865 INFO (OverseerThreadFactory-2813-thread-5-processing-n:127.0.0.1:56899_) [n:127.0.0.1:56899_ ] o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:56919_ for creating new replica
[junit4] 2> 773871 INFO (qtp107711230-6903) [n:127.0.0.1:56919_ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n45&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 773872 INFO (qtp107711230-6903) [n:127.0.0.1:56919_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 774902 INFO (qtp107711230-6903) [n:127.0.0.1:56919_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 774916 INFO (qtp107711230-6903) [n:127.0.0.1:56919_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.s.IndexSchema [collection1_shard2_replica_n45] Schema name=test
[junit4] 2> 775050 INFO (qtp107711230-6903) [n:127.0.0.1:56919_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 775079 INFO (qtp107711230-6903) [n:127.0.0.1:56919_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n45' using configuration from collection collection1, trusted=true
[junit4] 2> 775080 INFO (qtp107711230-6903) [n:127.0.0.1:56919_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard2.replica_n45' (registry 'solr.core.collection1.shard2.replica_n45') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b0a92c0
[junit4] 2> 775080 INFO (qtp107711230-6903) [n:127.0.0.1:56919_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 775080 INFO (qtp107711230-6903) [n:127.0.0.1:56919_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.c.SolrCore [[collection1_shard2_replica_n45] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/shard-3-001/cores/collection1_shard2_replica_n45], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.MultiThreadedOCPTest_D79C8EF0E1F5DC6A-001/shard-3-001/cores/collection1_shard2_replica_n45/data/]
[junit4] 2> 775083 INFO (qtp107711230-6903) [n:127.0.0.1:56919_ c:collection1 s:sh
[...truncated too long message...]
DC6A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDataProviderPerReplicaDetails
[junit4] 2> 3061829 INFO (TEST-TestPolicyCloud.testMetricsTag-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMetricsTag
[junit4] 2> 3061829 INFO (TEST-TestPolicyCloud.testMetricsTag-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing object cache...
[junit4] 2> 3061830 WARN (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 3061838 ERROR (simCloudManagerPool-11849-thread-40) [ ] o.a.s.h.RequestHandlerBase org.apache.solr.api.ApiBag$ExceptionWithErrObject: Error in command payload, errors: [{set-cluster-policy=[{cores=<10, node=#ANY}, {replica=<2, shard=#EACH, node=#ANY}, {metrics:abc=overseer, replica=0}], errorMessages=[Invalid metrics: param in {
[junit4] 2> "metrics:abc":"overseer",
[junit4] 2> "replica":0} must have at 2 or 3 segments after 'metrics:' separated by ':']}],
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:207)
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:145)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.simHandleSolrRequest(SimCloudManager.java:465)
[junit4] 2> at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.lambda$request$0(SimCloudManager.java:427)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 3061861 INFO (simCloudManagerPool-11849-thread-42) [ ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 4236170403 sessionWrapper.createTime 4236170376477429, this.sessionWrapper.createTime 4236170376477429
[junit4] 2> 3061877 INFO (TEST-TestPolicyCloud.testMetricsTag-seed#[D79C8EF0E1F5DC6A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMetricsTag
[junit4] 2> 3061877 WARN (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestPolicyCloud_D79C8EF0E1F5DC6A-001
[junit4] 2> Dec 17, 2017 11:34:54 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 3 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=false): {}, locale=ru, timezone=Asia/Tokyo
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=278049256,total=518520832
[junit4] 2> NOTE: All tests run in this JVM: [BlockJoinFacetSimpleTest, TestSolrQueryResponse, PluginInfoTest, TestComponentsName, TestCloudSchemaless, HttpSolrCallGetCoreTest, HLLUtilTest, OpenExchangeRatesOrgProviderTest, SolrIndexConfigTest, TestSchemaSimilarityResource, TestManagedSchemaAPI, TestAddFieldRealTimeGet, TestGenericDistributedQueue, TestHashQParserPlugin, MBeansHandlerTest, TestCollectionsAPIViaSolrCloudCluster, TestRebalanceLeaders, TestLuceneMatchVersion, DistributedIntervalFacetingTest, StressHdfsTest, UtilsToolTest, TestExclusionRuleCollectionAccess, TestSortByMinMaxFunction, TestSolrCoreSnapshots, SchemaVersionSpecificBehaviorTest, TestNumericTerms32, TestCustomStream, PreAnalyzedFieldTest, ConcurrentDeleteAndCreateCollectionTest, SystemInfoHandlerTest, UpdateLogTest, TestSolrCoreParser, LeaderElectionIntegrationTest, HdfsUnloadDistributedZkTest, TestAnalyzedSuggestions, TestFieldCacheReopen, MetricsHandlerTest, TestPivotHelperCode, SoftAutoCommitTest, TestMergePolicyConfig, DocValuesTest, TestTolerantUpdateProcessorCloud, DistributedFacetPivotSmallAdvancedTest, TestReqParamsAPI, ChaosMonkeySafeLeaderTest, DistributedExpandComponentTest, TestQueryTypes, RecoveryAfterSoftCommitTest, TestSolrCloudWithHadoopAuthPlugin, TestClusterStateProvider, ComputePlanActionTest, TestHighFrequencyDictionaryFactory, TestPushWriter, OpenCloseCoreStressTest, StatelessScriptUpdateProcessorFactoryTest, TestTrieFacet, TriLevelCompositeIdRoutingTest, DirectoryFactoryTest, TestManagedSynonymGraphFilterFactory, CloudExitableDirectoryReaderTest, TestSubQueryTransformerDistrib, RankQueryTest, TestStressVersions, HdfsRecoverLeaseTest, OverseerTaskQueueTest, TestFoldingMultitermQuery, TestBinaryResponseWriter, ZkSolrClientTest, TestSolrCLIRunExample, TestLRUStatsCache, TestBadConfig, TestFastOutputStream, TestLegacyNumericRangeQueryBuilder, DocumentAnalysisRequestHandlerTest, TestPointFields, RulesTest, TestFaceting, IndexSchemaRuntimeFieldTest, SSLMigrationTest, TestTrie, ClassificationUpdateProcessorIntegrationTest, TestInitParams, TestClusterProperties, TestSystemIdResolver, MultiThreadedOCPTest, DistributedQueueTest, TestGraphMLResponseWriter, TestRawTransformer, TestExceedMaxTermLength, TestSolrXml, FullSolrCloudDistribCmdsTest, TestXmlQParserPlugin, HdfsThreadLeakTest, TestLRUCache, TestSolrJ, TestStandardQParsers, DocValuesNotIndexedTest, TestExactSharedStatsCache, TestObjectReleaseTracker, TestStressLucene, TestDistribStateManager, SimpleFacetsTest, CollectionsAPIAsyncDistributedZkTest, TestDocTermOrds, PingRequestHandlerTest, CdcrBidirectionalTest, TestRecoveryHdfs, TestLockTree, SpatialHeatmapFacetsTest, HdfsCollectionsAPIDistributedZkTest, SecurityConfHandlerTest, BitVectorTest, TestPerFieldSimilarity, TestScoreJoinQPNoScore, TestAuthorizationFramework, TestSolrQueryParser, TemplateUpdateProcessorTest, TestUninvertingReader, CollectionStateFormat2Test, IgnoreCommitOptimizeUpdateProcessorFactoryTest, SolrCoreCheckLockOnStartupTest, TestFileDictionaryLookup, TestLegacyFieldReuse, TestPolicyCloud, TestBM25SimilarityFactory, V2StandaloneTest, SearchRateTriggerTest, SimpleCollectionCreateDeleteTest, TestFieldCacheSortRandom, TermVectorComponentTest, DistribDocExpirationUpdateProcessorTest, TestStressLiveNodes, BlockDirectoryTest, LegacyCloudClusterPropTest, TestRemoteStreaming, ZkFailoverTest, TestSchemalessBufferedUpdates, SolrIndexSplitterTest, StatsReloadRaceTest, SolrPluginUtilsTest, TestQueryUtils, TestRuleBasedAuthorizationPlugin, TestFastWriter, TestManagedStopFilterFactory, TestDocumentBuilder, TestConfig, DistributedFacetPivotWhiteBoxTest, TestLeaderElectionWithEmptyReplica, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestQueryWrapperFilter, UpdateParamsTest, TestReloadAndDeleteDocs, TestSystemCollAutoCreate, FileUtilsTest, CurrencyFieldTypeTest, BlockJoinFacetDistribTest, AutoAddReplicasPlanActionTest, DeleteReplicaTest, CollectionsAPIDistributedZkTest, TestPartialUpdateDeduplication, TestCollationField, HttpPartitionTest, TestBulkSchemaConcurrent, ShardRoutingCustomTest, QueryEqualityTest, BadComponentTest, EchoParamsTest, SolrCoreMetricManagerTest, DistributedMLTComponentTest, FullHLLTest, BasicZkTest, AtomicUpdatesTest, TestCloudInspectUtil, TestCrossCoreJoin, TestNumericRangeQuery32, TestCoreContainer, TestConfigSets, TestFieldCollectionResource, ManagedSchemaRoundRobinCloudTest, SynonymTokenizerTest, TestHdfsBackupRestoreCore, TestSQLHandler, NodeAddedTriggerTest, TestSolrConfigHandlerCloud, TestSSLRandomization, TestUseDocValuesAsStored2, MoreLikeThisHandlerTest, PreAnalyzedUpdateProcessorTest, RecoveryZkTest, TestRandomFaceting, LeaderElectionTest, TestDistributedSearch, TestRecovery, TermVectorComponentDistributedTest, DistributedTermsComponentTest, StatsComponentTest, PeerSyncTest, SimplePostToolTest, TestCoreDiscovery, SignatureUpdateProcessorFactoryTest, SuggesterFSTTest, SpatialFilterTest, SuggesterWFSTTest, TestCSVLoader, PolyFieldTest, TestPseudoReturnFields, TestAtomicUpdateErrorCases, DefaultValueUpdateProcessorTest, DocValuesMultiTest, DistanceFunctionTest, DebugComponentTest, IndexBasedSpellCheckerTest, LukeRequestHandlerTest, DirectSolrSpellCheckerTest, PrimitiveFieldTypeTest, TermsComponentTest, PathHierarchyTokenizerFactoryTest, FieldAnalysisRequestHandlerTest, ReturnFieldsTest, QueryParsingTest, JsonLoaderTest, HighlighterConfigTest, SOLR749Test, AlternateDirectoryTest, TestSolrIndexConfig, CopyFieldTest, TestStressRecovery, AssignTest, CollectionTooManyReplicasTest, ConnectionManagerTest, CustomCollectionTest, DeleteStatusTest, ForceLeaderTest, MigrateRouteKeyTest, MoveReplicaHDFSTest, NodeMutatorTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerCollectionConfigSetProcessorTest, OverseerRolesTest, ReplaceNodeTest, SharedFSAutoReplicaFailoverTest, SolrCloudExampleTest, TestAuthenticationFramework, TestCloudDeleteByQuery, TestCloudJSONFacetJoinDomain, TestCloudPivotFacet, TestCloudRecovery, TestCloudSearcherWarming, TestConfigSetsAPIExclusivity, TestCryptoKeys, TestDeleteCollectionOnDownNodes, TestDistribDocBasedVersion, TestDownShardTolerantSearch, TestLeaderInitiatedRecoveryThread, TestOnReconnectListenerSupport, TestPullReplica, TestShortCircuitedRequests, TestSolrCloudWithDelegationTokens, TestSolrCloudWithKerberosAlt, TestTolerantUpdateProcessorRandomCloud, TestUtilizeNode, ExecutePlanActionTest, HdfsAutoAddReplicasIntegrationTest, HttpTriggerListenerTest, SystemLogListenerTest, TestExecutePlanAction, TestNodeAddedTrigger, TestPolicyCloud]
[junit4] Completed [585/763 (5!)] on J1 in 1.72s, 7 tests, 1 error <<< FAILURES!
[...truncated 48703 lines...]
[JENKINS] Lucene-Solr-7.x-MacOSX (64bit/jdk1.8.0) - Build # 346 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/346/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication
Error Message:
Index: 0, Size: 0
Stack Trace:
java.lang.IndexOutOfBoundsException: Index: 0, Size: 0
at __randomizedtesting.SeedInfo.seed([94527D99E0862AEC:801A26CCC38197F2]:0)
at java.util.ArrayList.rangeCheck(ArrayList.java:653)
at java.util.ArrayList.get(ArrayList.java:429)
at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication(TestReplicationHandler.java:561)
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 12915 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 1848415 INFO (SUITE-TestReplicationHandler-seed#[94527D99E0862AEC]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_94527D99E0862AEC-001/init-core-data-001
[junit4] 2> 1848416 INFO (SUITE-TestReplicationHandler-seed#[94527D99E0862AEC]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1848419 INFO (SUITE-TestReplicationHandler-seed#[94527D99E0862AEC]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 1848419 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterWrite2Slave
[junit4] 2> 1848420 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_94527D99E0862AEC-001/solr-instance-001/collection1
[junit4] 2> 1848428 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1848429 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3203a412{/solr,null,AVAILABLE}
[junit4] 2> 1848431 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@41fc3dce{HTTP/1.1,[http/1.1]}{127.0.0.1:50933}
[junit4] 2> 1848431 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.e.j.s.Server Started @1852126ms
[junit4] 2> 1848431 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_94527D99E0862AEC-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=50933}
[junit4] 2> 1848431 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1848431 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0
[junit4] 2> 1848431 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1848431 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1848431 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-18T01:11:13.125Z
[junit4] 2> 1848432 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_94527D99E0862AEC-001/solr-instance-001/solr.xml
[junit4] 2> 1848436 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1848436 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1848436 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@c2b2d29, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1848484 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c2b2d29
[junit4] 2> 1848494 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c2b2d29
[junit4] 2> 1848495 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c2b2d29
[junit4] 2> 1848497 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_94527D99E0862AEC-001/solr-instance-001/.
[junit4] 2> 1848497 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1848498 INFO (coreLoadExecutor-6799-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1848514 INFO (coreLoadExecutor-6799-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 1848520 INFO (coreLoadExecutor-6799-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1848523 INFO (coreLoadExecutor-6799-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1848523 INFO (coreLoadExecutor-6799-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_94527D99E0862AEC-001/solr-instance-001/./collection1, trusted=true
[junit4] 2> 1848523 INFO (coreLoadExecutor-6799-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@c2b2d29
[junit4] 2> 1848524 INFO (coreLoadExecutor-6799-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1848524 INFO (coreLoadExecutor-6799-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_94527D99E0862AEC-001/solr-instance-001/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_94527D99E0862AEC-001/solr-instance-001/./collection1/data/]
[junit4] 2> 1848526 INFO (coreLoadExecutor-6799-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=33, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7149716665876363]
[junit4] 2> 1848572 INFO (coreLoadExecutor-6799-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1848572 INFO (coreLoadExecutor-6799-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1848573 INFO (coreLoadExecutor-6799-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8748597137131636]
[junit4] 2> 1848574 INFO (coreLoadExecutor-6799-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@24dea791[collection1] main]
[junit4] 2> 1848574 INFO (coreLoadExecutor-6799-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_94527D99E0862AEC-001/solr-instance-001/collection1/conf
[junit4] 2> 1848576 INFO (coreLoadExecutor-6799-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1848576 INFO (coreLoadExecutor-6799-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1848577 INFO (searcherExecutor-6800-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@24dea791[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1848579 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_94527D99E0862AEC-001/solr-instance-002/collection1
[junit4] 2> 1848585 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1848587 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@d25d0bb{/solr,null,AVAILABLE}
[junit4] 2> 1848587 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@21c44b3a{HTTP/1.1,[http/1.1]}{127.0.0.1:50934}
[junit4] 2> 1848587 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.e.j.s.Server Started @1852283ms
[junit4] 2> 1848587 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_94527D99E0862AEC-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=50934}
[junit4] 2> 1848588 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1848588 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0
[junit4] 2> 1848588 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1848588 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1848588 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-18T01:11:13.282Z
[junit4] 2> 1848589 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_94527D99E0862AEC-001/solr-instance-002/solr.xml
[junit4] 2> 1848596 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1848596 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1848597 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@c2b2d29, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1848652 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c2b2d29
[junit4] 2> 1848661 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c2b2d29
[junit4] 2> 1848661 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c2b2d29
[junit4] 2> 1848664 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_94527D99E0862AEC-001/solr-instance-002/.
[junit4] 2> 1848664 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[94527D99E0862AEC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1848665 INFO (coreLoadExecutor-6809-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1848680 INFO (coreLoadExecutor-6809-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 1848705 INFO (coreLoadExecutor-6809-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1848707 INFO (coreLoadExecutor-6809-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1848707 INFO (coreLoadExecutor-6809-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_94527D99E0862AEC-001/solr-instance-002/./collection1, trusted=true
[junit4] 2> 1848708 INFO (coreLoadExecutor-6809-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@c2b2d29
[junit4] 2> 1848708 INFO (coreLoadExecutor-6809-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1848708 INFO (coreLoadExecutor-6809-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_94527D99E0862AEC-001/solr-instance-002/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_94527D99E0862AEC-001/solr-instance-002/./collection1/data/]
[junit4] 2> 1848713 INFO (coreLoadExecutor-6809-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=33, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7149716665876363]
[junit4] 2> 1848760 INFO (coreLoadExecutor-6809-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1848760 INFO (coreLoadExecutor-6809-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1848761 INFO (coreLoadExecutor-6809-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8748597137131636]
[junit4] 2> 1848761 INFO (coreLoadExecutor-6809-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@77536b47[collection1] main]
[junit4] 2> 1848761 INFO (coreLoadExecutor-6809-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_94527D99E0862AEC-001/solr-instance-002/collection1/conf
[junit4] 2> 1848764 INFO (coreLoadExecutor-6809-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1848764 INFO (coreLoadExecutor-6809-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1848765 INFO (searcherExecutor-6810-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@77536b47[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1848772 INFO (qtp112593049-16124) [ 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> 1848772 INFO (qtp112593049-16122) [ 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> 1848772 INFO (indexFetcher-6814-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 1848772 INFO (indexFetcher-6814-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 1848773 INFO (indexFetcher-6814-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1848773 INFO (indexFetcher-6814-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's version: 0
[junit4] 2> 1848773 INFO (indexFetcher-6814-thread-1) [ x:collection1] o.a.s.h.IndexFetcher New index in Master. Deleting mine...
[junit4] 2> 1848773 INFO (indexFetcher-6814-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1848773 INFO (indexFetcher-6814-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@975b7d1 commitCommandVersion:0
[junit4] 2> 1848773 INFO (indexFetcher-6814-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5d25ad45[collection1] main]
[junit4] 2> 1848773 INFO (indexFetcher-6814-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1848773 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 0
[junit4] 2> 1848774 INFO (searcherExecutor-6810-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5d25ad45[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1848774 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 1848775 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 1848775 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 1848776 INFO (qtp112593049-16123) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 1848776 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 1848777 INFO (qtp112593049-16122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 1848778 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 1848778 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 1848779 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 1848779 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 1848780 INFO (qtp112593049-16123) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 1848780 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 1848781 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 1848781 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 1848782 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15]} 0 0
[junit4] 2> 1848782 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16]} 0 0
[junit4] 2> 1848783 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17]} 0 0
[junit4] 2> 1848783 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18]} 0 0
[junit4] 2> 1848783 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19]} 0 0
[junit4] 2> 1848784 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
[junit4] 2> 1848784 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21]} 0 0
[junit4] 2> 1848785 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22]} 0 0
[junit4] 2> 1848785 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23]} 0 0
[junit4] 2> 1848786 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24]} 0 0
[junit4] 2> 1848786 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25]} 0 0
[junit4] 2> 1848787 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26]} 0 0
[junit4] 2> 1848787 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27]} 0 0
[junit4] 2> 1848787 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28]} 0 0
[junit4] 2> 1848788 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29]} 0 0
[junit4] 2> 1848788 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30]} 0 0
[junit4] 2> 1848789 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31]} 0 0
[junit4] 2> 1848789 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32]} 0 0
[junit4] 2> 1848789 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33]} 0 0
[junit4] 2> 1848790 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34]} 0 0
[junit4] 2> 1848790 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35]} 0 0
[junit4] 2> 1848791 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36]} 0 0
[junit4] 2> 1848791 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37]} 0 0
[junit4] 2> 1848792 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38]} 0 0
[junit4] 2> 1848792 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39]} 0 0
[junit4] 2> 1848792 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40]} 0 0
[junit4] 2> 1848793 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41]} 0 0
[junit4] 2> 1848793 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[42]} 0 0
[junit4] 2> 1848793 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[43]} 0 0
[junit4] 2> 1848794 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[44]} 0 0
[junit4] 2> 1848794 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[45]} 0 0
[junit4] 2> 1848795 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[46]} 0 0
[junit4] 2> 1848795 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[47]} 0 0
[junit4] 2> 1848796 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[48]} 0 0
[junit4] 2> 1848796 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[49]} 0 0
[junit4] 2> 1848796 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[50]} 0 0
[junit4] 2> 1848797 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[51]} 0 0
[junit4] 2> 1848797 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[52]} 0 0
[junit4] 2> 1848798 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[53]} 0 0
[junit4] 2> 1848798 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[54]} 0 0
[junit4] 2> 1848799 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[55]} 0 0
[junit4] 2> 1848799 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[56]} 0 0
[junit4] 2> 1848800 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[57]} 0 0
[junit4] 2> 1848800 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[58]} 0 0
[junit4] 2> 1848800 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[59]} 0 0
[junit4] 2> 1848801 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[60]} 0 0
[junit4] 2> 1848801 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[61]} 0 0
[junit4] 2> 1848802 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[62]} 0 0
[junit4] 2> 1848802 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[63]} 0 0
[junit4] 2> 1848803 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[64]} 0 0
[junit4] 2> 1848803 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[65]} 0 0
[junit4] 2> 1848804 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[66]} 0 0
[junit4] 2> 1848804 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[67]} 0 0
[junit4] 2> 1848805 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[68]} 0 0
[junit4] 2> 1848805 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[69]} 0 0
[junit4] 2> 1848805 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[70]} 0 0
[junit4] 2> 1848806 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[71]} 0 0
[junit4] 2> 1848807 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[72]} 0 0
[junit4] 2> 1848807 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[73]} 0 0
[junit4] 2> 1848808 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[74]} 0 0
[junit4] 2> 1848808 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[75]} 0 0
[junit4] 2> 1848809 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[76]} 0 0
[junit4] 2> 1848809 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[77]} 0 0
[junit4] 2> 1848809 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[78]} 0 0
[junit4] 2> 1848810 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[79]} 0 0
[junit4] 2> 1848810 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[80]} 0 0
[junit4] 2> 1848811 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[81]} 0 0
[junit4] 2> 1848811 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[82]} 0 0
[junit4] 2> 1848811 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[83]} 0 0
[junit4] 2> 1848812 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[84]} 0 0
[junit4] 2> 1848812 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[85]} 0 0
[junit4] 2> 1848813 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[86]} 0 0
[junit4] 2> 1848813 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[87]} 0 0
[junit4] 2> 1848813 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[88]} 0 0
[junit4] 2> 1848814 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[89]} 0 0
[junit4] 2> 1848814 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[90]} 0 0
[junit4] 2> 1848815 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[91]} 0 0
[junit4] 2> 1848815 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[92]} 0 0
[junit4] 2> 1848816 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[93]} 0 0
[junit4] 2> 1848816 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[94]} 0 0
[junit4] 2> 1848817 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[95]} 0 0
[junit4] 2> 1848817 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[96]} 0 0
[junit4] 2> 1848817 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[97]} 0 0
[junit4] 2> 1848818 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[98]} 0 0
[junit4] 2> 1848818 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[99]} 0 0
[junit4] 2> 1848819 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[100]} 0 0
[junit4] 2> 1848819 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[101]} 0 0
[junit4] 2> 1848820 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[102]} 0 0
[junit4] 2> 1848820 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[103]} 0 0
[junit4] 2> 1848820 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[104]} 0 0
[junit4] 2> 1848821 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[105]} 0 0
[junit4] 2> 1848821 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[106]} 0 0
[junit4] 2> 1848822 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[107]} 0 0
[junit4] 2> 1848822 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[108]} 0 0
[junit4] 2> 1848822 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[109]} 0 0
[junit4] 2> 1848823 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[110]} 0 0
[junit4] 2> 1848823 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[111]} 0 0
[junit4] 2> 1848824 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[112]} 0 0
[junit4] 2> 1848824 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[113]} 0 0
[junit4] 2> 1848825 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[114]} 0 0
[junit4] 2> 1848825 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[115]} 0 0
[junit4] 2> 1848825 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[116]} 0 0
[junit4] 2> 1848826 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[117]} 0 0
[junit4] 2> 1848827 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[118]} 0 0
[junit4] 2> 1848827 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[119]} 0 0
[junit4] 2> 1848827 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[120]} 0 0
[junit4] 2> 1848828 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[121]} 0 0
[junit4] 2> 1848828 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[122]} 0 0
[junit4] 2> 1848829 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[123]} 0 0
[junit4] 2> 1848829 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[124]} 0 0
[junit4] 2> 1848829 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[125]} 0 0
[junit4] 2> 1848830 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[126]} 0 0
[junit4] 2> 1848831 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[127]} 0 1
[junit4] 2> 1848832 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[128]} 0 0
[junit4] 2> 1848833 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[129]} 0 0
[junit4] 2> 1848833 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[130]} 0 0
[junit4] 2> 1848833 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[131]} 0 0
[junit4] 2> 1848834 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[132]} 0 0
[junit4] 2> 1848834 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[133]} 0 0
[junit4] 2> 1848835 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[134]} 0 0
[junit4] 2> 1848835 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[135]} 0 0
[junit4] 2> 1848835 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[136]} 0 0
[junit4] 2> 1848836 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[137]} 0 0
[junit4] 2> 1848836 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[138]} 0 0
[junit4] 2> 1848837 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[139]} 0 0
[junit4] 2> 1848837 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[140]} 0 0
[junit4] 2> 1848837 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[141]} 0 0
[junit4] 2> 1848838 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[142]} 0 0
[junit4] 2> 1848838 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[143]} 0 0
[junit4] 2> 1848838 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[144]} 0 0
[junit4] 2> 1848839 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[145]} 0 0
[junit4] 2> 1848839 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[146]} 0 0
[junit4] 2> 1848840 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[147]} 0 0
[junit4] 2> 1848840 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[148]} 0 0
[junit4] 2> 1848840 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[149]} 0 0
[junit4] 2> 1848841 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[150]} 0 0
[junit4] 2> 1848841 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[151]} 0 0
[junit4] 2> 1848842 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[152]} 0 0
[junit4] 2> 1848842 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[153]} 0 0
[junit4] 2> 1848842 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[154]} 0 0
[junit4] 2> 1848843 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[155]} 0 0
[junit4] 2> 1848843 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[156]} 0 0
[junit4] 2> 1848844 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[157]} 0 0
[junit4] 2> 1848844 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[158]} 0 0
[junit4] 2> 1848844 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[159]} 0 0
[junit4] 2> 1848845 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[160]} 0 0
[junit4] 2> 1848845 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[161]} 0 0
[junit4] 2> 1848846 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[162]} 0 0
[junit4] 2> 1848846 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[163]} 0 0
[junit4] 2> 1848847 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[164]} 0 0
[junit4] 2> 1848847 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[165]} 0 0
[junit4] 2> 1848847 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[166]} 0 0
[junit4] 2> 1848848 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[167]} 0 0
[junit4] 2> 1848848 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[168]} 0 0
[junit4] 2> 1848849 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[169]} 0 0
[junit4] 2> 1848849 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[170]} 0 0
[junit4] 2> 1848849 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[171]} 0 0
[junit4] 2> 1848850 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[172]} 0 0
[junit4] 2> 1848850 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[173]} 0 0
[junit4] 2> 1848851 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[174]} 0 0
[junit4] 2> 1848851 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[175]} 0 0
[junit4] 2> 1848852 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[176]} 0 0
[junit4] 2> 1848852 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[177]} 0 0
[junit4] 2> 1848852 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[178]} 0 0
[junit4] 2> 1848853 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[179]} 0 0
[junit4] 2> 1848853 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[180]} 0 0
[junit4] 2> 1848853 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[181]} 0 0
[junit4] 2> 1848854 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[182]} 0 0
[junit4] 2> 1848854 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[183]} 0 0
[junit4] 2> 1848855 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[184]} 0 0
[junit4] 2> 1848855 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[185]} 0 0
[junit4] 2> 1848856 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[186]} 0 0
[junit4] 2> 1848856 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[187]} 0 0
[junit4] 2> 1848857 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[188]} 0 0
[junit4] 2> 1848857 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[189]} 0 0
[junit4] 2> 1848858 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[190]} 0 0
[junit4] 2> 1848858 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[191]} 0 0
[junit4] 2> 1848859 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[192]} 0 0
[junit4] 2> 1848859 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[193]} 0 0
[junit4] 2> 1848859 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[194]} 0 0
[junit4] 2> 1848860 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[195]} 0 0
[junit4] 2> 1848860 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[196]} 0 0
[junit4] 2> 1848861 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[197]} 0 0
[junit4] 2> 1848861 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[198]} 0 0
[junit4] 2> 1848862 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[199]} 0 0
[junit4] 2> 1848862 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[200]} 0 0
[junit4] 2> 1848863 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[201]} 0 0
[junit4] 2> 1848863 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[202]} 0 0
[junit4] 2> 1848863 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[203]} 0 0
[junit4] 2> 1848864 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[204]} 0 0
[junit4] 2> 1848864 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[205]} 0 0
[junit4] 2> 1848865 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[206]} 0 0
[junit4] 2> 1848865 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[207]} 0 0
[junit4] 2> 1848866 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[208]} 0 0
[junit4] 2> 1848866 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[209]} 0 0
[junit4] 2> 1848866 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[210]} 0 0
[junit4] 2> 1848867 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[211]} 0 0
[junit4] 2> 1848867 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[212]} 0 0
[junit4] 2> 1848868 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[213]} 0 0
[junit4] 2> 1848868 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[214]} 0 0
[junit4] 2> 1848869 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[215]} 0 0
[junit4] 2> 1848869 INFO (qtp112593049-16123) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[216]} 0 0
[junit4] 2> 1848870 INFO (qtp112593049-16122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[217]} 0 0
[junit4] 2> 1848871 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[218]} 0 0
[junit4] 2> 1848871 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[219]} 0 0
[junit4] 2> 1848871 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[220]} 0 0
[junit4] 2> 1848872 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[221]} 0 0
[junit4] 2> 1848872 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[222]} 0 0
[junit4] 2> 1848873 INFO (qtp112593049-16123) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[223]} 0 0
[junit4] 2> 1848873 INFO (qtp112593049-16122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[224]} 0 0
[junit4] 2> 1848873 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[225]} 0 0
[junit4] 2> 1848874 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[226]} 0 0
[junit4] 2> 1848874 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[227]} 0 0
[junit4] 2> 1848874 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[228]} 0 0
[junit4] 2> 1848875 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[229]} 0 0
[junit4] 2> 1848875 INFO (qtp112593049-16123) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[230]} 0 0
[junit4] 2> 1848876 INFO (qtp112593049-16122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[231]} 0 0
[junit4] 2> 1848876 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[232]} 0 0
[junit4] 2> 1848876 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[233]} 0 0
[junit4] 2> 1848877 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[234]} 0 0
[junit4] 2> 1848877 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[235]} 0 0
[junit4] 2> 1848878 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[236]} 0 0
[junit4] 2> 1848878 INFO (qtp112593049-16123) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[237]} 0 0
[junit4] 2> 1848878 INFO (qtp112593049-16122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[238]} 0 0
[junit4] 2> 1848879 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[239]} 0 0
[junit4] 2> 1848879 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[240]} 0 0
[junit4] 2> 1848879 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[241]} 0 0
[junit4] 2> 1848880 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[242]} 0 0
[junit4] 2> 1848880 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[243]} 0 0
[junit4] 2> 1848880 INFO (qtp112593049-16123) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[244]} 0 0
[junit4] 2> 1848881 INFO (qtp112593049-16122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[245]} 0 0
[junit4] 2> 1848881 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[246]} 0 0
[junit4] 2> 1848882 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[247]} 0 0
[junit4] 2> 1848882 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[248]} 0 0
[junit4] 2> 1848882 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[249]} 0 0
[junit4] 2> 1848883 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[250]} 0 0
[junit4] 2> 1848883 INFO (qtp112593049-16123) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[251]} 0 0
[junit4] 2> 1848883 INFO (qtp112593049-16122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[252]} 0 0
[junit4] 2> 1848884 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[253]} 0 0
[junit4] 2> 1848884 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[254]} 0 0
[junit4] 2> 1848885 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[255]} 0 0
[junit4] 2> 1848885 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[256]} 0 0
[junit4] 2> 1848885 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[257]} 0 0
[junit4] 2> 1848886 INFO (qtp112593049-16123) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[258]} 0 0
[junit4] 2> 1848886 INFO (qtp112593049-16122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[259]} 0 0
[junit4] 2> 1848886 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[260]} 0 0
[junit4] 2> 1848887 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[261]} 0 0
[junit4] 2> 1848887 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[262]} 0 0
[junit4] 2> 1848887 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[263]} 0 0
[junit4] 2> 1848888 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[264]} 0 0
[junit4] 2> 1848888 INFO (qtp112593049-16123) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[265]} 0 0
[junit4] 2> 1848888 INFO (qtp112593049-16122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[266]} 0 0
[junit4] 2> 1848889 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[267]} 0 0
[junit4] 2> 1848889 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[268]} 0 0
[junit4] 2> 1848890 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[269]} 0 0
[junit4] 2> 1848890 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[270]} 0 0
[junit4] 2> 1848890 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[271]} 0 0
[junit4] 2> 1848890 INFO (qtp112593049-16123) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[272]} 0 0
[junit4] 2> 1848891 INFO (qtp112593049-16122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[273]} 0 0
[junit4] 2> 1848891 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[274]} 0 0
[junit4] 2> 1848892 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[275]} 0 0
[junit4] 2> 1848892 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[276]} 0 0
[junit4] 2> 1848892 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[277]} 0 0
[junit4] 2> 1848893 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[278]} 0 0
[junit4] 2> 1848893 INFO (qtp112593049-16123) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[279]} 0 0
[junit4] 2> 1848894 INFO (qtp112593049-16122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[280]} 0 0
[junit4] 2> 1848894 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[281]} 0 0
[junit4] 2> 1848895 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[282]} 0 0
[junit4] 2> 1848895 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[283]} 0 0
[junit4] 2> 1848895 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[284]} 0 0
[junit4] 2> 1848896 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[285]} 0 0
[junit4] 2> 1848896 INFO (qtp112593049-16123) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[286]} 0 0
[junit4] 2> 1848897 INFO (qtp112593049-16122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[287]} 0 0
[junit4] 2> 1848897 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[288]} 0 0
[junit4] 2> 1848897 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[289]} 0 0
[junit4] 2> 1848898 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[290]} 0 0
[junit4] 2> 1848898 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[291]} 0 0
[junit4] 2> 1848898 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[292]} 0 0
[junit4] 2> 1848899 INFO (qtp112593049-16123) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[293]} 0 0
[junit4] 2> 1848899 INFO (qtp112593049-16122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[294]} 0 0
[junit4] 2> 1848900 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[295]} 0 0
[junit4] 2> 1848900 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[296]} 0 0
[junit4] 2> 1848901 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[297]} 0 0
[junit4] 2> 1848901 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[298]} 0 0
[junit4] 2> 1848902 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[299]} 0 0
[junit4] 2> 1848902 INFO (qtp112593049-16123) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[300]} 0 0
[junit4] 2> 1848903 INFO (qtp112593049-16122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[301]} 0 0
[junit4] 2> 1848903 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[302]} 0 0
[junit4] 2> 1848903 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[303]} 0 0
[junit4] 2> 1848904 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[304]} 0 0
[junit4] 2> 1848904 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[305]} 0 0
[junit4] 2> 1848905 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[306]} 0 0
[junit4] 2> 1848905 INFO (qtp112593049-16123) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[307]} 0 0
[junit4] 2> 1848906 INFO (qtp112593049-16122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[308]} 0 0
[junit4] 2> 1848906 INFO (qtp112593049-16124) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[309]} 0 0
[junit4] 2> 1848906 INFO (qtp112593049-16120) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[310]} 0 0
[junit4] 2> 1848907 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[311]} 0 0
[junit4] 2> 1848907 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[312]} 0 0
[junit4] 2> 1848908 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[313]} 0 0
[junit4] 2> 1848908 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[314]} 0 0
[junit4] 2> 1848909 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[315]} 0 0
[junit4] 2> 1848909 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[316]} 0 0
[junit4] 2> 1848909 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[317]} 0 0
[junit4] 2> 1848910 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[318]} 0 0
[junit4] 2> 1848910 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[319]} 0 0
[junit4] 2> 1848911 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[320]} 0 0
[junit4] 2> 1848911 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[321]} 0 0
[junit4] 2> 1848911 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[322]} 0 0
[junit4] 2> 1848912 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[323]} 0 0
[junit4] 2> 1848912 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[324]} 0 0
[junit4] 2> 1848913 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[325]} 0 0
[junit4] 2> 1848913 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[326]} 0 0
[junit4] 2> 1848913 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[327]} 0 0
[junit4] 2> 1848914 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[328]} 0 0
[junit4] 2> 1848914 INFO (qtp112593049-16127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[329]} 0 0
[junit4] 2> 1848915 INFO (qtp112593049-16125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[330]} 0 0
[junit4] 2> 1848915 INFO (qtp112593049-16126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[331]} 0 0
[junit4] 2> 1848916 INFO (qtp112593049-16123) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]
[...truncated too long message...]
stDetails-seed#[94527D99E0862AEC]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@522cbdac: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@e880cd0
[junit4] 2> 1942422 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[94527D99E0862AEC]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1942423 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[94527D99E0862AEC]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@70480711: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@193a824b
[junit4] 2> 1942424 INFO (coreCloseExecutor-7465-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@50447833
[junit4] 2> 1942424 INFO (coreCloseExecutor-7465-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1346664499
[junit4] 2> 1942424 INFO (coreCloseExecutor-7465-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3b6d5743: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2f124c7d
[junit4] 2> 1942426 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[94527D99E0862AEC]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5e8dc49a{/solr,null,UNAVAILABLE}
[junit4] 2> 1942428 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[94527D99E0862AEC]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7c9d0bbf{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1942429 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[94527D99E0862AEC]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1457707615
[junit4] 2> 1942429 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[94527D99E0862AEC]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1942429 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[94527D99E0862AEC]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1f56cb19: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1042f031
[junit4] 2> 1942431 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[94527D99E0862AEC]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1942431 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[94527D99E0862AEC]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@53c336cd: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@e880cd0
[junit4] 2> 1942433 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[94527D99E0862AEC]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1942433 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[94527D99E0862AEC]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@d50122: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@193a824b
[junit4] 2> 1942435 INFO (coreCloseExecutor-7467-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@15fc3894
[junit4] 2> 1942435 INFO (coreCloseExecutor-7467-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=368851092
[junit4] 2> 1942435 INFO (coreCloseExecutor-7467-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@79aaaf9b: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2561384c
[junit4] 2> 1942439 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[94527D99E0862AEC]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2c30b73f{/solr,null,UNAVAILABLE}
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_94527D99E0862AEC-001
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=true): {}, locale=nn-NO, timezone=Africa/Gaborone
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=128409128,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestEmbeddedSolrServerAdminHandler, PropertiesRequestHandlerTest, TestStressUserVersions, RegexBoostProcessorTest, DeleteNodeTest, RegexBytesRefFilterTest, ResourceLoaderTest, SliceStateTest, SolrTestCaseJ4Test, TestSimDistributedQueue, BlockJoinFacetRandomTest, TestConfigSetProperties, MultiTermTest, DirectSolrConnectionTest, WordBreakSolrSpellCheckerTest, DistributedSuggestComponentTest, HdfsRecoveryZkTest, TestJoin, TestBlobHandler, SyncSliceTest, TestUniqueKeyFieldResource, TestConfigOverlay, ZkCLITest, QueryResultKeyTest, SmileWriterTest, TestLargeCluster, SegmentsInfoRequestHandlerTest, SolrCloudReportersTest, BadCopyFieldTest, TestSimpleQParserPlugin, ExitableDirectoryReaderTest, DistributedFacetPivotLongTailTest, TestPseudoReturnFields, TestComputePlanAction, TestIndexingPerformance, BJQParserTest, SignatureUpdateProcessorFactoryTest, TestDistributedSearch, TestNumericRangeQuery64, TestCollectionAPI, AutoAddReplicasIntegrationTest, HighlighterMaxOffsetTest, TestLFUCache, PathHierarchyTokenizerFactoryTest, ShardRoutingTest, TestGeoJSONResponseWriter, TestFieldResource, DistributedDebugComponentTest, TestFieldTypeResource, DisMaxRequestHandlerTest, TestRecovery, PrimUtilsTest, DistribJoinFromCollectionTest, TestLegacyNumericUtils, HdfsWriteToMultipleCollectionsTest, TestSegmentSorting, SolrGraphiteReporterTest, TestSolrConfigHandler, SpatialRPTFieldTypeTest, SolrMetricsIntegrationTest, DistributedFacetSimpleRefinementLongTailTest, CollectionsAPISolrJTest, CdcrReplicationHandlerTest, TolerantUpdateProcessorTest, BigEndianAscendingWordSerializerTest, V2ApiIntegrationTest, TestLocalFSCloudBackupRestore, ConfigSetsAPITest, DeleteInactiveReplicaTest, TestLegacyField, DistanceFunctionTest, DistributedSpellCheckComponentTest, HdfsSyncSliceTest, TestClassicSimilarityFactory, ClassificationUpdateProcessorFactoryTest, TestPhraseSuggestions, AnalyticsMergeStrategyTest, TestJsonFacets, TestSlowCompositeReaderWrapper, TestSolrCoreProperties, TestCloudSearcherWarming, TestSchemaManager, HdfsChaosMonkeySafeLeaderTest, TestReloadDeadlock, TestInfoStreamLogging, VMParamsZkACLAndCredentialsProvidersTest, CSVRequestHandlerTest, TestSurroundQueryParser, TestRetrieveFieldsOptimizer, TestQuerySenderListener, AddBlockUpdateTest, DeleteShardTest, OverriddenZkACLAndCredentialsProvidersTest, TestFieldCacheSort, OverseerTest, TestPKIAuthenticationPlugin, CursorPagingTest, HdfsBasicDistributedZkTest, CircularListTest, TestSha256AuthenticationProvider, TestAtomicUpdateErrorCases, TestNamedUpdateProcessors, TestDynamicLoading, OutputWriterTest, TestCollationFieldDocValues, TestCloudPseudoReturnFields, TestDistribIDF, TestSolrCloudSnapshots, TestConfigSetsAPIZkFailure, TestFiltering, TestSchemaVersionResource, GraphQueryTest, TestUpdate, TestNumericTerms64, TestCloudManagedSchema, TestRandomDVFaceting, DistanceUnitsTest, TestWriterPerf, TestMaxTokenLenTokenizer, TestOmitPositions, TestFuzzyAnalyzedSuggestions, ClassificationUpdateProcessorTest, RequestLoggingTest, OverseerModifyCollectionTest, TestTestInjection, BlobRepositoryCloudTest, TestDocBasedVersionConstraints, OverseerStatusTest, StatsComponentTest, TestCollapseQParserPlugin, PeerSyncWithIndexFingerprintCachingTest, PreAnalyzedFieldManagedSchemaCloudTest, TestNestedDocsSort, HLLSerializationTest, HdfsNNFailoverTest, TestBlendedInfixSuggestions, TestCorePropertiesReload, BlockCacheTest, TestFreeTextSuggestions, DateMathParserTest, TestReversedWildcardFilterFactory, ExecutePlanActionTest, BasicAuthIntegrationTest, TestRestManager, TestEmbeddedSolrServerSchemaAPI, ChangedSchemaMergeTest, AutoScalingHandlerTest, TestBinaryField, ParsingFieldUpdateProcessorsTest, TestDownShardTolerantSearch, BinaryUpdateRequestHandlerTest, PrimitiveFieldTypeTest, RAMDirectoryFactoryTest, PKIAuthenticationIntegrationTest, TestTrackingShardHandlerFactory, TestSolrCloudWithDelegationTokens, TestLeaderElectionZkExpiry, TestOverriddenPrefixQueryForCustomFieldType, SystemLogListenerTest, ForceLeaderTest, TestDistributedStatsComponentCardinality, SharedFSAutoReplicaFailoverTest, TestLazyCores, NumericFieldsTest, TestXmlQParser, SpellCheckComponentTest, CoreSorterTest, UnloadDistributedZkTest, SpatialFilterTest, TestTolerantUpdateProcessorRandomCloud, CleanupOldIndexTest, DateRangeFieldTest, TestTolerantSearch, TestShardHandlerFactory, DistribCursorPagingTest, TestUtils, TestSolr4Spatial, TriggerIntegrationTest, TestExactStatsCache, BaseCdcrDistributedZkTest, LeaderFailureAfterFreshStartTest, TestRandomRequestDistribution, TimeZoneUtilsTest, TestCoreBackup, NoCacheHeaderTest, SolrMetricManagerTest, TestFieldCache, NodeLostTriggerTest, TestZkAclsWithHadoopAuth, TestReplicationHandler]
[junit4] Completed [399/763 (1!)] on J0 in 94.04s, 16 tests, 1 error, 1 skipped <<< FAILURES!
[...truncated 49336 lines...]