You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2018/03/29 13:10:20 UTC
[JENKINS] Lucene-Solr-NightlyTests-master - Build # 1515 - Still
unstable
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1515/
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler
Error Message:
ObjectTracker found 4 object(s) that were not released!!! [SolrCore, InternalHttpClient, MockDirectoryWrapper, MockDirectoryWrapper] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.core.SolrCore at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1040) at org.apache.solr.core.SolrCore.reload(SolrCore.java:657) at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:1301) at org.apache.solr.handler.IndexFetcher.lambda$reloadCore$0(IndexFetcher.java:921) at java.lang.Thread.run(Thread.java:748) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.http.impl.client.InternalHttpClient at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:289) at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:298) at org.apache.solr.handler.IndexFetcher.createHttpClient(IndexFetcher.java:226) at org.apache.solr.handler.IndexFetcher.<init>(IndexFetcher.java:268) at org.apache.solr.handler.ReplicationHandler.inform(ReplicationHandler.java:1190) at org.apache.solr.core.SolrResourceLoader.inform(SolrResourceLoader.java:696) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:988) at org.apache.solr.core.SolrCore.reload(SolrCore.java:657) at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:1301) at org.apache.solr.handler.IndexFetcher.lambda$reloadCore$0(IndexFetcher.java:921) at java.lang.Thread.run(Thread.java:748) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MockDirectoryWrapper at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348) at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:503) at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:347) at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:420) at org.apache.solr.handler.ReplicationHandler.lambda$setupPolling$12(ReplicationHandler.java:1159) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MockDirectoryWrapper at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348) at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:95) at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:762) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:955) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:864) at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1051) at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:647) at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
java.lang.AssertionError: ObjectTracker found 4 object(s) that were not released!!! [SolrCore, InternalHttpClient, MockDirectoryWrapper, MockDirectoryWrapper]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.core.SolrCore
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1040)
at org.apache.solr.core.SolrCore.reload(SolrCore.java:657)
at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:1301)
at org.apache.solr.handler.IndexFetcher.lambda$reloadCore$0(IndexFetcher.java:921)
at java.lang.Thread.run(Thread.java:748)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.http.impl.client.InternalHttpClient
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:289)
at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:298)
at org.apache.solr.handler.IndexFetcher.createHttpClient(IndexFetcher.java:226)
at org.apache.solr.handler.IndexFetcher.<init>(IndexFetcher.java:268)
at org.apache.solr.handler.ReplicationHandler.inform(ReplicationHandler.java:1190)
at org.apache.solr.core.SolrResourceLoader.inform(SolrResourceLoader.java:696)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:988)
at org.apache.solr.core.SolrCore.reload(SolrCore.java:657)
at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:1301)
at org.apache.solr.handler.IndexFetcher.lambda$reloadCore$0(IndexFetcher.java:921)
at java.lang.Thread.run(Thread.java:748)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MockDirectoryWrapper
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:503)
at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:347)
at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:420)
at org.apache.solr.handler.ReplicationHandler.lambda$setupPolling$12(ReplicationHandler.java:1159)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MockDirectoryWrapper
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:95)
at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:762)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:955)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:864)
at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1051)
at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:647)
at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([D1979BF5E818AF83]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:302)
at sun.reflect.GeneratedMethodAccessor43.invoke(Unknown Source)
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$7.evaluate(RandomizedRunner.java:897)
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.client.solrj.impl.CloudSolrClientTest.checkCollectionParameters
Error Message:
Could not find collection:multicollection1
Stack Trace:
java.lang.AssertionError: Could not find collection:multicollection1
at __randomizedtesting.SeedInfo.seed([604C31EE0DEE4F34:9B5BA25E54B99883]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:155)
at org.apache.solr.client.solrj.impl.CloudSolrClientTest.checkCollectionParameters(CloudSolrClientTest.java:565)
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 2165 lines...]
[junit4] JVM J2: stdout was not empty, see: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/build/core/test/temp/junit4-J2-20180329_034030_1152512134562309329852.sysout
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] codec: Lucene70, pf: Direct, dvf: Asserting
[junit4] <<< JVM J2: EOF ----
[...truncated 12021 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 4398916 INFO (SUITE-TestReplicationHandler-seed#[D1979BF5E818AF83]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/init-core-data-001
[junit4] 2> 4398917 WARN (SUITE-TestReplicationHandler-seed#[D1979BF5E818AF83]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=123 numCloses=123
[junit4] 2> 4398917 INFO (SUITE-TestReplicationHandler-seed#[D1979BF5E818AF83]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 4398919 INFO (SUITE-TestReplicationHandler-seed#[D1979BF5E818AF83]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 4398920 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestIndexFetchWithMasterUrl
[junit4] 2> 4398921 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-001/collection1
[junit4] 2> 4398923 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 4398924 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4398924 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4398924 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 4398925 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4db1a7c0{/solr,null,AVAILABLE}
[junit4] 2> 4398926 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6943427f{HTTP/1.1,[http/1.1]}{127.0.0.1:44602}
[junit4] 2> 4398926 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.Server Started @4399056ms
[junit4] 2> 4398926 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=44602}
[junit4] 2> 4398926 ERROR (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4398926 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4398926 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 4398926 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 4398926 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4398926 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-29T11:20:20.274Z
[junit4] 2> 4398926 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-001/solr.xml
[junit4] 2> 4398930 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 4398930 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4398931 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@bb396f8, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 4398967 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@bb396f8
[junit4] 2> 4398975 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@bb396f8
[junit4] 2> 4398975 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@bb396f8
[junit4] 2> 4398977 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-001/.
[junit4] 2> 4398977 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 4398977 INFO (coreLoadExecutor-10591-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 4398988 INFO (coreLoadExecutor-10591-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 4398994 INFO (coreLoadExecutor-10591-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 4398996 INFO (coreLoadExecutor-10591-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 4398996 INFO (coreLoadExecutor-10591-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-001/./collection1, trusted=true
[junit4] 2> 4398997 INFO (coreLoadExecutor-10591-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@bb396f8
[junit4] 2> 4398997 INFO (coreLoadExecutor-10591-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 4398997 INFO (coreLoadExecutor-10591-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-001/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-001/./collection1/data/]
[junit4] 2> 4398999 INFO (coreLoadExecutor-10591-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@2eaf39da
[junit4] 2> 4399037 INFO (coreLoadExecutor-10591-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4399037 INFO (coreLoadExecutor-10591-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4399038 INFO (coreLoadExecutor-10591-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=0.275390625, floorSegmentMB=0.4130859375, forceMergeDeletesPctAllowed=24.53106580346271, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5462854740862665
[junit4] 2> 4399038 INFO (coreLoadExecutor-10591-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@39e65d75[collection1] main]
[junit4] 2> 4399038 INFO (coreLoadExecutor-10591-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-001/collection1/conf
[junit4] 2> 4399039 INFO (coreLoadExecutor-10591-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 4399039 INFO (coreLoadExecutor-10591-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4399040 INFO (searcherExecutor-10592-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@39e65d75[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4399041 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-002/collection1
[junit4] 2> 4399044 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 4399045 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4399045 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4399045 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 4399045 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6dc29b95{/solr,null,AVAILABLE}
[junit4] 2> 4399045 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@54429b4a{HTTP/1.1,[http/1.1]}{127.0.0.1:40275}
[junit4] 2> 4399045 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.Server Started @4399176ms
[junit4] 2> 4399045 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=40275}
[junit4] 2> 4399046 ERROR (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4399046 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4399046 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 4399046 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 4399046 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4399046 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-29T11:20:20.394Z
[junit4] 2> 4399046 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-002/solr.xml
[junit4] 2> 4399057 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 4399057 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4399058 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@bb396f8, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 4399093 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@bb396f8
[junit4] 2> 4399103 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@bb396f8
[junit4] 2> 4399103 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@bb396f8
[junit4] 2> 4399105 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-002/.
[junit4] 2> 4399105 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 4399105 INFO (coreLoadExecutor-10601-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 4399115 INFO (coreLoadExecutor-10601-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 4399122 INFO (coreLoadExecutor-10601-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 4399124 INFO (coreLoadExecutor-10601-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 4399124 INFO (coreLoadExecutor-10601-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-002/./collection1, trusted=true
[junit4] 2> 4399124 INFO (coreLoadExecutor-10601-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@bb396f8
[junit4] 2> 4399124 INFO (coreLoadExecutor-10601-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 4399124 INFO (coreLoadExecutor-10601-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-002/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-002/./collection1/data/]
[junit4] 2> 4399126 INFO (coreLoadExecutor-10601-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@7254d95
[junit4] 2> 4399166 INFO (coreLoadExecutor-10601-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4399166 INFO (coreLoadExecutor-10601-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4399167 INFO (coreLoadExecutor-10601-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=0.275390625, floorSegmentMB=0.4130859375, forceMergeDeletesPctAllowed=24.53106580346271, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5462854740862665
[junit4] 2> 4399167 INFO (coreLoadExecutor-10601-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1007e66e[collection1] main]
[junit4] 2> 4399168 INFO (coreLoadExecutor-10601-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-002/collection1/conf
[junit4] 2> 4399169 INFO (coreLoadExecutor-10601-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 4399169 INFO (coreLoadExecutor-10601-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4399170 INFO (searcherExecutor-10602-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1007e66e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4399172 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@54429b4a{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 4399172 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1857039354
[junit4] 2> 4399172 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 4399172 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1c2fa4a2: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1ddc6e19
[junit4] 2> 4399178 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 4399178 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@751ee58b: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3a6592bf
[junit4] 2> 4399182 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 4399182 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1a73e901: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@64cd62dc
[junit4] 2> 4399184 INFO (coreCloseExecutor-10607-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2a51f6d2
[junit4] 2> 4399184 INFO (coreCloseExecutor-10607-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=2a51f6d2
[junit4] 2> 4399184 INFO (coreCloseExecutor-10607-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7f0f1a28: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2e5e6ba3
[junit4] 2> 4399193 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6dc29b95{/solr,null,UNAVAILABLE}
[junit4] 2> 4399193 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 4399195 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 4399196 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4399196 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4399196 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 4399196 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4f0d3545{/solr,null,AVAILABLE}
[junit4] 2> 4399196 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@25ba66a9{HTTP/1.1,[http/1.1]}{127.0.0.1:39413}
[junit4] 2> 4399196 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.e.j.s.Server Started @4399327ms
[junit4] 2> 4399196 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=39413}
[junit4] 2> 4399197 ERROR (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4399197 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4399197 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 4399197 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 4399197 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4399197 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-29T11:20:20.545Z
[junit4] 2> 4399197 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-002/solr.xml
[junit4] 2> 4399201 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 4399201 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4399202 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@bb396f8, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 4399236 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@bb396f8
[junit4] 2> 4399244 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@bb396f8
[junit4] 2> 4399245 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@bb396f8
[junit4] 2> 4399246 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-002/.
[junit4] 2> 4399246 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[D1979BF5E818AF83]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 4399247 INFO (coreLoadExecutor-10614-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 4399266 INFO (coreLoadExecutor-10614-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 4399272 INFO (coreLoadExecutor-10614-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 4399274 INFO (coreLoadExecutor-10614-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 4399274 INFO (coreLoadExecutor-10614-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-002/./collection1, trusted=true
[junit4] 2> 4399274 INFO (coreLoadExecutor-10614-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@bb396f8
[junit4] 2> 4399274 INFO (coreLoadExecutor-10614-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 4399274 INFO (coreLoadExecutor-10614-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-002/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-002/./collection1/data/]
[junit4] 2> 4399276 INFO (coreLoadExecutor-10614-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@5308567d
[junit4] 2> 4399332 INFO (coreLoadExecutor-10614-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4399332 INFO (coreLoadExecutor-10614-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4399333 INFO (coreLoadExecutor-10614-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=0.275390625, floorSegmentMB=0.4130859375, forceMergeDeletesPctAllowed=24.53106580346271, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5462854740862665
[junit4] 2> 4399333 INFO (coreLoadExecutor-10614-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5d94a4fb[collection1] main]
[junit4] 2> 4399334 INFO (coreLoadExecutor-10614-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_D1979BF5E818AF83-001/solr-instance-002/collection1/conf
[junit4] 2> 4399334 INFO (coreLoadExecutor-10614-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4399335 INFO (searcherExecutor-10615-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5d94a4fb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4399338 INFO (qtp241437190-118087) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 4399339 INFO (qtp241437190-118087) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 4399341 INFO (qtp1053653183-118125) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 4399341 INFO (qtp1053653183-118125) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 4399341 INFO (qtp1053653183-118127) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4399341 INFO (qtp1053653183-118127) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@707f333e commitCommandVersion:0
[junit4] 2> 4399343 INFO (qtp1053653183-118127) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3f85d97a[collection1] main]
[junit4] 2> 4399343 INFO (qtp1053653183-118127) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 4399344 INFO (searcherExecutor-10615-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3f85d97a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4399344 INFO (qtp1053653183-118127) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
[junit4] 2> 4399345 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 0
[junit4] 2> 4399345 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 4399346 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 4399346 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 4399346 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 4399346 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 4399347 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 4399347 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 4399347 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 4399347 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 4399348 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 4399348 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 4399348 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 4399349 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 4399349 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 4399349 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15]} 0 0
[junit4] 2> 4399349 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16]} 0 0
[junit4] 2> 4399350 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17]} 0 0
[junit4] 2> 4399350 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18]} 0 0
[junit4] 2> 4399350 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19]} 0 0
[junit4] 2> 4399350 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
[junit4] 2> 4399351 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21]} 0 0
[junit4] 2> 4399351 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22]} 0 0
[junit4] 2> 4399351 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23]} 0 0
[junit4] 2> 4399351 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24]} 0 0
[junit4] 2> 4399352 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25]} 0 0
[junit4] 2> 4399352 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26]} 0 0
[junit4] 2> 4399352 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27]} 0 0
[junit4] 2> 4399352 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28]} 0 0
[junit4] 2> 4399353 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29]} 0 0
[junit4] 2> 4399353 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30]} 0 0
[junit4] 2> 4399353 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31]} 0 0
[junit4] 2> 4399353 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32]} 0 0
[junit4] 2> 4399354 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33]} 0 0
[junit4] 2> 4399354 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34]} 0 0
[junit4] 2> 4399354 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35]} 0 0
[junit4] 2> 4399354 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36]} 0 0
[junit4] 2> 4399355 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37]} 0 0
[junit4] 2> 4399355 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38]} 0 0
[junit4] 2> 4399355 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39]} 0 0
[junit4] 2> 4399355 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40]} 0 0
[junit4] 2> 4399356 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41]} 0 0
[junit4] 2> 4399356 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[42]} 0 0
[junit4] 2> 4399356 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[43]} 0 0
[junit4] 2> 4399356 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[44]} 0 0
[junit4] 2> 4399357 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[45]} 0 0
[junit4] 2> 4399357 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[46]} 0 0
[junit4] 2> 4399357 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[47]} 0 0
[junit4] 2> 4399357 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[48]} 0 0
[junit4] 2> 4399358 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[49]} 0 0
[junit4] 2> 4399358 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[50]} 0 0
[junit4] 2> 4399358 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[51]} 0 0
[junit4] 2> 4399358 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[52]} 0 0
[junit4] 2> 4399359 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[53]} 0 0
[junit4] 2> 4399359 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[54]} 0 0
[junit4] 2> 4399359 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[55]} 0 0
[junit4] 2> 4399359 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[56]} 0 0
[junit4] 2> 4399360 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[57]} 0 0
[junit4] 2> 4399360 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[58]} 0 0
[junit4] 2> 4399360 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[59]} 0 0
[junit4] 2> 4399360 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[60]} 0 0
[junit4] 2> 4399361 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[61]} 0 0
[junit4] 2> 4399361 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[62]} 0 0
[junit4] 2> 4399361 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[63]} 0 0
[junit4] 2> 4399361 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[64]} 0 0
[junit4] 2> 4399362 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[65]} 0 0
[junit4] 2> 4399362 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[66]} 0 0
[junit4] 2> 4399362 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[67]} 0 0
[junit4] 2> 4399362 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[68]} 0 0
[junit4] 2> 4399363 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[69]} 0 0
[junit4] 2> 4399363 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[70]} 0 0
[junit4] 2> 4399363 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[71]} 0 0
[junit4] 2> 4399364 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[72]} 0 0
[junit4] 2> 4399364 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[73]} 0 0
[junit4] 2> 4399364 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[74]} 0 0
[junit4] 2> 4399364 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[75]} 0 0
[junit4] 2> 4399365 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[76]} 0 0
[junit4] 2> 4399365 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[77]} 0 0
[junit4] 2> 4399365 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[78]} 0 0
[junit4] 2> 4399365 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[79]} 0 0
[junit4] 2> 4399365 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[80]} 0 0
[junit4] 2> 4399366 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[81]} 0 0
[junit4] 2> 4399366 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[82]} 0 0
[junit4] 2> 4399366 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[83]} 0 0
[junit4] 2> 4399366 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[84]} 0 0
[junit4] 2> 4399367 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[85]} 0 0
[junit4] 2> 4399367 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[86]} 0 0
[junit4] 2> 4399367 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[87]} 0 0
[junit4] 2> 4399368 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[88]} 0 0
[junit4] 2> 4399368 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[89]} 0 0
[junit4] 2> 4399368 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[90]} 0 0
[junit4] 2> 4399368 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[91]} 0 0
[junit4] 2> 4399369 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[92]} 0 0
[junit4] 2> 4399369 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[93]} 0 0
[junit4] 2> 4399369 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[94]} 0 0
[junit4] 2> 4399369 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[95]} 0 0
[junit4] 2> 4399369 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[96]} 0 0
[junit4] 2> 4399370 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[97]} 0 0
[junit4] 2> 4399370 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[98]} 0 0
[junit4] 2> 4399370 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[99]} 0 0
[junit4] 2> 4399370 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[100]} 0 0
[junit4] 2> 4399371 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[101]} 0 0
[junit4] 2> 4399371 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[102]} 0 0
[junit4] 2> 4399371 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[103]} 0 0
[junit4] 2> 4399372 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[104]} 0 0
[junit4] 2> 4399372 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[105]} 0 0
[junit4] 2> 4399372 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[106]} 0 0
[junit4] 2> 4399372 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[107]} 0 0
[junit4] 2> 4399373 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[108]} 0 0
[junit4] 2> 4399373 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[109]} 0 0
[junit4] 2> 4399373 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[110]} 0 0
[junit4] 2> 4399374 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[111]} 0 0
[junit4] 2> 4399374 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[112]} 0 0
[junit4] 2> 4399374 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[113]} 0 0
[junit4] 2> 4399374 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[114]} 0 0
[junit4] 2> 4399375 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[115]} 0 0
[junit4] 2> 4399375 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[116]} 0 0
[junit4] 2> 4399375 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[117]} 0 0
[junit4] 2> 4399375 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[118]} 0 0
[junit4] 2> 4399376 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[119]} 0 0
[junit4] 2> 4399376 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[120]} 0 0
[junit4] 2> 4399376 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[121]} 0 0
[junit4] 2> 4399376 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[122]} 0 0
[junit4] 2> 4399377 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[123]} 0 0
[junit4] 2> 4399377 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[124]} 0 0
[junit4] 2> 4399377 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[125]} 0 0
[junit4] 2> 4399377 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[126]} 0 0
[junit4] 2> 4399378 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[127]} 0 0
[junit4] 2> 4399378 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[128]} 0 0
[junit4] 2> 4399378 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[129]} 0 0
[junit4] 2> 4399379 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[130]} 0 0
[junit4] 2> 4399379 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[131]} 0 0
[junit4] 2> 4399379 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[132]} 0 0
[junit4] 2> 4399379 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[133]} 0 0
[junit4] 2> 4399380 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[134]} 0 0
[junit4] 2> 4399380 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[135]} 0 0
[junit4] 2> 4399380 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[136]} 0 0
[junit4] 2> 4399380 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[137]} 0 0
[junit4] 2> 4399381 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[138]} 0 0
[junit4] 2> 4399381 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[139]} 0 0
[junit4] 2> 4399381 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[140]} 0 0
[junit4] 2> 4399382 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[141]} 0 0
[junit4] 2> 4399382 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[142]} 0 0
[junit4] 2> 4399382 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[143]} 0 0
[junit4] 2> 4399382 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[144]} 0 0
[junit4] 2> 4399383 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[145]} 0 0
[junit4] 2> 4399383 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[146]} 0 0
[junit4] 2> 4399383 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[147]} 0 0
[junit4] 2> 4399383 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[148]} 0 0
[junit4] 2> 4399384 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[149]} 0 0
[junit4] 2> 4399384 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[150]} 0 0
[junit4] 2> 4399384 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[151]} 0 0
[junit4] 2> 4399384 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[152]} 0 0
[junit4] 2> 4399385 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[153]} 0 0
[junit4] 2> 4399385 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[154]} 0 0
[junit4] 2> 4399385 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[155]} 0 0
[junit4] 2> 4399385 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[156]} 0 0
[junit4] 2> 4399386 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[157]} 0 0
[junit4] 2> 4399386 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[158]} 0 0
[junit4] 2> 4399386 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[159]} 0 0
[junit4] 2> 4399386 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[160]} 0 0
[junit4] 2> 4399387 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[161]} 0 0
[junit4] 2> 4399387 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[162]} 0 0
[junit4] 2> 4399387 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[163]} 0 0
[junit4] 2> 4399388 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[164]} 0 0
[junit4] 2> 4399388 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[165]} 0 0
[junit4] 2> 4399388 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[166]} 0 0
[junit4] 2> 4399388 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[167]} 0 0
[junit4] 2> 4399389 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[168]} 0 0
[junit4] 2> 4399389 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[169]} 0 0
[junit4] 2> 4399389 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[170]} 0 0
[junit4] 2> 4399389 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[171]} 0 0
[junit4] 2> 4399389 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[172]} 0 0
[junit4] 2> 4399390 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[173]} 0 0
[junit4] 2> 4399390 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[174]} 0 0
[junit4] 2> 4399390 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[175]} 0 0
[junit4] 2> 4399390 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[176]} 0 0
[junit4] 2> 4399391 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[177]} 0 0
[junit4] 2> 4399391 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[178]} 0 0
[junit4] 2> 4399391 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[179]} 0 0
[junit4] 2> 4399392 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[180]} 0 0
[junit4] 2> 4399392 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[181]} 0 0
[junit4] 2> 4399392 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[182]} 0 0
[junit4] 2> 4399392 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[183]} 0 0
[junit4] 2> 4399393 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[184]} 0 0
[junit4] 2> 4399393 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[185]} 0 0
[junit4] 2> 4399393 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[186]} 0 0
[junit4] 2> 4399393 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[187]} 0 0
[junit4] 2> 4399393 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[188]} 0 0
[junit4] 2> 4399394 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[189]} 0 0
[junit4] 2> 4399394 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[190]} 0 0
[junit4] 2> 4399394 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[191]} 0 0
[junit4] 2> 4399394 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[192]} 0 0
[junit4] 2> 4399395 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[193]} 0 0
[junit4] 2> 4399395 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[194]} 0 0
[junit4] 2> 4399395 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[195]} 0 0
[junit4] 2> 4399396 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[196]} 0 0
[junit4] 2> 4399396 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[197]} 0 0
[junit4] 2> 4399396 INFO (qtp241437190-118089) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[198]} 0 0
[junit4] 2> 4399396 INFO (qtp241437190-118091) [ x:collection1] o.a.s.u.p.LogUpdate
[...truncated too long message...]
egistry=solr.core.foo.shard1.replica_n2, tag=2ff58d89
[junit4] 2> 493908 INFO (coreCloseExecutor-573-thread-1) [n:127.0.0.1:44248_solr c:foo s:shard1 r:core_node5 x:foo_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@18710238: rootName = solr_44248, domain = solr.core.foo.shard1.replica_n2, service url = null, agent id = null] for registry solr.core.foo.shard1.replica_n2 / com.codahale.metrics.MetricRegistry@1b9761d
[junit4] 2> 493978 INFO (coreCloseExecutor-571-thread-6) [n:127.0.0.1:36640_solr c:localShardsTestColl s:shard2 r:core_node9 x:localShardsTestColl_shard2_replica_n6] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.localShardsTestColl.shard2.leader, tag=2641dd39
[junit4] 2> 493982 INFO (coreCloseExecutor-571-thread-1) [n:127.0.0.1:36640_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard1.leader, tag=42ebcd26
[junit4] 2> 494071 INFO (coreCloseExecutor-572-thread-3) [n:127.0.0.1:34484_solr c:localShardsTestColl s:shard1 r:core_node5 x:localShardsTestColl_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.localShardsTestColl.shard1.leader, tag=3659e196
[junit4] 2> 494072 INFO (coreCloseExecutor-572-thread-2) [n:127.0.0.1:34484_solr c:foo s:shard1 r:core_node6 x:foo_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.foo.shard1.replica_n3, tag=37d0e1c8
[junit4] 2> 494072 INFO (coreCloseExecutor-572-thread-2) [n:127.0.0.1:34484_solr c:foo s:shard1 r:core_node6 x:foo_shard1_replica_n3] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@67116d0c: rootName = solr_34484, domain = solr.core.foo.shard1.replica_n3, service url = null, agent id = null] for registry solr.core.foo.shard1.replica_n3 / com.codahale.metrics.MetricRegistry@53728cba
[junit4] 2> 494087 INFO (coreCloseExecutor-573-thread-1) [n:127.0.0.1:44248_solr c:foo s:shard1 r:core_node5 x:foo_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.foo.shard1.leader, tag=2ff58d89
[junit4] 2> 494091 INFO (coreCloseExecutor-572-thread-2) [n:127.0.0.1:34484_solr c:foo s:shard1 r:core_node6 x:foo_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.foo.shard1.leader, tag=37d0e1c8
[junit4] 2> 494091 INFO (coreCloseExecutor-573-thread-3) [n:127.0.0.1:44248_solr c:localShardsTestColl s:shard1 r:core_node7 x:localShardsTestColl_shard1_replica_n4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.localShardsTestColl.shard1.leader, tag=3a8c419a
[junit4] 2> 494095 INFO (coreCloseExecutor-572-thread-7) [n:127.0.0.1:34484_solr c:2nd_collection s:shard2 r:core_node4 x:2nd_collection_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.2nd_collection.shard2.leader, tag=775eb3f7
[junit4] 2> 494124 INFO (coreCloseExecutor-572-thread-5) [n:127.0.0.1:34484_solr c:localShardsTestColl s:shard3 r:core_node17 x:localShardsTestColl_shard3_replica_n13] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.localShardsTestColl.shard3.leader, tag=212d2b8d
[junit4] 2> 494124 INFO (coreCloseExecutor-573-thread-5) [n:127.0.0.1:44248_solr c:2nd_collection s:shard1 r:core_node3 x:2nd_collection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.2nd_collection.shard1.leader, tag=44c036d8
[junit4] 2> 494424 INFO (jetty-closer-352-thread-3) [ ] o.a.s.c.Overseer Overseer (id=72363266776301576-127.0.0.1:44248_solr-n_0000000000) closing
[junit4] 2> 494470 INFO (OverseerStateUpdate-72363266776301576-127.0.0.1:44248_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:44248_solr
[junit4] 2> 494532 WARN (OverseerAutoScalingTriggerThread-72363266776301576-127.0.0.1:44248_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 494552 INFO (jetty-closer-352-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4694cb74{/solr,null,UNAVAILABLE}
[junit4] 2> 494552 INFO (jetty-closer-352-thread-1) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 494726 INFO (jetty-closer-352-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1da84517{/solr,null,UNAVAILABLE}
[junit4] 2> 494726 INFO (jetty-closer-352-thread-2) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 496213 INFO (jetty-closer-352-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@21ac083f{/solr,null,UNAVAILABLE}
[junit4] 2> 496214 INFO (jetty-closer-352-thread-3) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 496215 ERROR (SUITE-CloudSolrClientTest-seed#[604C31EE0DEE4F34]-worker) [ ] 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> 496216 INFO (SUITE-CloudSolrClientTest-seed#[604C31EE0DEE4F34]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37760 37760
[junit4] 2> 496379 INFO (Thread-328) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37760 37760
[junit4] 2> 496379 WARN (Thread-328) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 22 /solr/aliases.json
[junit4] 2> 12 /solr/collections/localShardsTestColl/terms/shard2
[junit4] 2> 12 /solr/collections/localShardsTestColl/terms/shard1
[junit4] 2> 11 /solr/collections/stale_state_test_col/terms/shard1
[junit4] 2> 9 /solr/collections/foo/terms/shard1
[junit4] 2> 9 /solr/collections/localShardsTestColl/terms/shard3
[junit4] 2> 3 /solr/security.json
[junit4] 2> 3 /solr/configs/conf
[junit4] 2> 3 /solr/collections/2nd_collection/terms/shard2
[junit4] 2> 3 /solr/collections/collection1/terms/shard1
[junit4] 2> 3 /solr/collections/collection1/terms/shard2
[junit4] 2> 3 /solr/collections/overwrite/terms/shard1
[junit4] 2> 2 /solr/collections/2nd_collection/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 50 /solr/collections/localShardsTestColl/state.json
[junit4] 2> 25 /solr/collections/foo/state.json
[junit4] 2> 22 /solr/clusterprops.json
[junit4] 2> 22 /solr/clusterstate.json
[junit4] 2> 16 /solr/collections/stale_state_test_col/state.json
[junit4] 2> 16 /solr/collections/collection1/state.json
[junit4] 2> 16 /solr/collections/2nd_collection/state.json
[junit4] 2> 6 /solr/collections/overwrite/state.json
[junit4] 2> 2 /solr/collections/localShardsTestColl/leader_elect/shard1/election/72363266776301577-core_node3-n_0000000000
[junit4] 2> 2 /solr/collections/localShardsTestColl/leader_elect/shard3/election/72363266776301577-core_node16-n_0000000000
[junit4] 2> 2 /solr/collections/localShardsTestColl/leader_elect/shard2/election/72363266776301576-core_node14-n_0000000000
[junit4] 2> 2 /solr/overseer_elect/election/72363266776301576-127.0.0.1:44248_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 22 /solr/live_nodes
[junit4] 2> 22 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.impl.CloudSolrClientTest_604C31EE0DEE4F34-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {multiDefault=Lucene50(blocksize=128), a_t=PostingsFormat(name=LuceneFixedGap), title_s=Lucene50(blocksize=128), id=PostingsFormat(name=Direct), text=BlockTreeOrds(blocksize=128)}, docValues:{_version_=DocValuesFormat(name=Asserting), multiDefault=DocValuesFormat(name=Direct), title_s=DocValuesFormat(name=Direct), intDefault=DocValuesFormat(name=Asserting), id=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=885, maxMBSortInHeap=7.648771354974474, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4abaea93), locale=de-CH, timezone=Asia/Rangoon
[junit4] 2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=4,threads=1,free=201186352,total=494927872
[junit4] 2> NOTE: All tests run in this JVM: [StreamExpressionToExpessionTest, TestV2Request, TestDocumentObjectBinder, CeilingEvaluatorTest, SolrExampleStreamingBinaryTest, JsonValidatorTest, AbsoluteValueEvaluatorTest, JDBCStreamTest, SolrPingTest, QueryResponseTest, TestFastInputStream, CorrelationEvaluatorTest, SolrQueryTest, CumulativeProbabilityEvaluatorTest, ClientUtilsTest, GraphTest, TestLBHttpSolrClient, TestSolrProperties, ConcurrentUpdateSolrClientTest, UniformDistributionEvaluatorTest, SolrZkClientTest, SolrDocumentTest, TestUpdateRequestCodec, CloudSolrClientMultiConstructorTest, SolrExampleBinaryTest, SolrExampleJettyTest, LargeVolumeBinaryJettyTest, TestSpellCheckResponse, MergeIndexesEmbeddedTest, TestJavaBinCodec, ContentStreamTest, TestXMLEscaping, DocumentAnalysisResponseTest, SolrExceptionTest, FieldAnalysisResponseTest, NamedListTest, FacetFieldTest, UsingSolrJRefGuideExamplesTest, CollectionAdminRequestRequiredParamsTest, SolrSchemalessExampleTest, CloudSolrClientBuilderTest, CloudSolrClientTest]
[junit4] Completed [127/147 (1!)] on J1 in 154.02s, 15 tests, 1 failure <<< FAILURES!
[...truncated 48627 lines...]