You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/03/12 02:01:57 UTC
[JENKINS] Lucene-Solr-7.x-MacOSX (64bit/jdk-9) - Build # 501 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/501/
Java: 64bit/jdk-9 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.TestDistributedSearch.test
Error Message:
Error from server at http://127.0.0.1:49673/_m/collection1: java.lang.NullPointerException at org.apache.solr.search.grouping.distributed.responseprocessor.StoredFieldsShardResponseProcessor.process(StoredFieldsShardResponseProcessor.java:38) at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:579) at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:562) at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:423) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:195) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2503) at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:711) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:517) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:384) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:330) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:527) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) at org.eclipse.jetty.server.Server.handle(Server.java:530) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:382) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.base/java.lang.Thread.run(Thread.java:844)
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:49673/_m/collection1: java.lang.NullPointerException
at org.apache.solr.search.grouping.distributed.responseprocessor.StoredFieldsShardResponseProcessor.process(StoredFieldsShardResponseProcessor.java:38)
at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:579)
at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:562)
at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:423)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:195)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:2503)
at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:711)
at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:517)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:384)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:330)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:527)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
at org.eclipse.jetty.server.Server.handle(Server.java:530)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:382)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.base/java.lang.Thread.run(Thread.java:844)
at __randomizedtesting.SeedInfo.seed([8EED7088B1F7D439:6B94F521F0BB9C1]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:957)
at org.apache.solr.TestDistributedSearch.queryRandomUpServer(TestDistributedSearch.java:1168)
at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1130)
at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:992)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1019)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at 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.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 12400 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> 382040 INFO (SUITE-TestDistributedSearch-seed#[8EED7088B1F7D439]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_8EED7088B1F7D439-001/init-core-data-001
[junit4] 2> 382040 INFO (SUITE-TestDistributedSearch-seed#[8EED7088B1F7D439]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 382041 INFO (SUITE-TestDistributedSearch-seed#[8EED7088B1F7D439]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="https://issues.apache.org/jira/browse/SOLR-9061") w/ MAC_OS_X supressed clientAuth
[junit4] 2> 382041 INFO (SUITE-TestDistributedSearch-seed#[8EED7088B1F7D439]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_m/
[junit4] 2> 384711 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_8EED7088B1F7D439-001/tempDir-001/control/cores/collection1
[junit4] 2> 384714 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T22:27:37+01:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 384717 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 384718 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 384718 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 384719 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7fc67f5d{/_m,null,AVAILABLE}
[junit4] 2> 384720 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@615bde19{HTTP/1.1,[http/1.1]}{127.0.0.1:49585}
[junit4] 2> 384720 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.e.j.s.Server Started @387589ms
[junit4] 2> 384720 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_m, hostPort=49585, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_8EED7088B1F7D439-001/tempDir-001/control/cores}
[junit4] 2> 384721 ERROR (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 384722 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0
[junit4] 2> 384722 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 384722 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 384722 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-12T00:47:25.100228Z
[junit4] 2> 384722 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_8EED7088B1F7D439-001/tempDir-001/control/solr.xml
[junit4] 2> 384731 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 384731 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 384732 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@306ae72b, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 384861 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@306ae72b
[junit4] 2> 384880 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@306ae72b
[junit4] 2> 384880 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@306ae72b
[junit4] 2> 384889 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_8EED7088B1F7D439-001/tempDir-001/control/cores
[junit4] 2> 384889 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 384890 INFO (coreLoadExecutor-1444-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 384891 INFO (coreLoadExecutor-1444-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 384945 INFO (coreLoadExecutor-1444-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 385009 INFO (coreLoadExecutor-1444-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 385275 INFO (coreLoadExecutor-1444-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 385312 INFO (coreLoadExecutor-1444-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 385313 INFO (coreLoadExecutor-1444-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@306ae72b
[junit4] 2> 385313 INFO (coreLoadExecutor-1444-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 385313 INFO (coreLoadExecutor-1444-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_8EED7088B1F7D439-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 385327 INFO (coreLoadExecutor-1444-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=18.73828125, floorSegmentMB=1.7216796875, forceMergeDeletesPctAllowed=12.98920453246791, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 385444 INFO (coreLoadExecutor-1444-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 385444 INFO (coreLoadExecutor-1444-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 385446 INFO (coreLoadExecutor-1444-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 385446 INFO (coreLoadExecutor-1444-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 385447 INFO (coreLoadExecutor-1444-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=21.7265625, floorSegmentMB=2.0634765625, forceMergeDeletesPctAllowed=9.750634810723144, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 385449 INFO (coreLoadExecutor-1444-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@37d7e405[collection1] main]
[junit4] 2> 385450 INFO (coreLoadExecutor-1444-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 385575 INFO (coreLoadExecutor-1444-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 385825 INFO (coreLoadExecutor-1444-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 385825 INFO (searcherExecutor-1445-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 385826 INFO (coreLoadExecutor-1444-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1594690787034005504
[junit4] 2> 385872 INFO (searcherExecutor-1445-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 385872 INFO (searcherExecutor-1445-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 385872 INFO (searcherExecutor-1445-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 385895 INFO (searcherExecutor-1445-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 385926 INFO (searcherExecutor-1445-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 385927 INFO (searcherExecutor-1445-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 385927 INFO (searcherExecutor-1445-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 385928 INFO (searcherExecutor-1445-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 385929 INFO (searcherExecutor-1445-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@37d7e405[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 386903 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_8EED7088B1F7D439-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 386904 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T22:27:37+01:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 386906 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 386906 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 386906 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 386906 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4d407ca2{/_m,null,AVAILABLE}
[junit4] 2> 386909 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@58942f67{HTTP/1.1,[http/1.1]}{127.0.0.1:49588}
[junit4] 2> 386909 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.e.j.s.Server Started @389778ms
[junit4] 2> 386909 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_m, hostPort=49588, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_8EED7088B1F7D439-001/tempDir-001/shard0/cores}
[junit4] 2> 386909 ERROR (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 386911 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0
[junit4] 2> 386911 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 386911 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 386911 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-12T00:47:27.289599Z
[junit4] 2> 386911 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_8EED7088B1F7D439-001/tempDir-001/shard0/solr.xml
[junit4] 2> 386916 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 386916 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 386917 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@306ae72b, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 387017 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@306ae72b
[junit4] 2> 387035 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@306ae72b
[junit4] 2> 387035 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@306ae72b
[junit4] 2> 387038 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_8EED7088B1F7D439-001/tempDir-001/shard0/cores
[junit4] 2> 387038 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 387039 INFO (coreLoadExecutor-1455-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 387040 INFO (coreLoadExecutor-1455-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 387070 INFO (coreLoadExecutor-1455-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 387113 INFO (coreLoadExecutor-1455-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 387626 INFO (coreLoadExecutor-1455-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 387641 INFO (coreLoadExecutor-1455-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 387641 INFO (coreLoadExecutor-1455-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@306ae72b
[junit4] 2> 387641 INFO (coreLoadExecutor-1455-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 387642 INFO (coreLoadExecutor-1455-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_8EED7088B1F7D439-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 387644 INFO (coreLoadExecutor-1455-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=18.73828125, floorSegmentMB=1.7216796875, forceMergeDeletesPctAllowed=12.98920453246791, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 387854 INFO (coreLoadExecutor-1455-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 387854 INFO (coreLoadExecutor-1455-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 387865 INFO (coreLoadExecutor-1455-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 387865 INFO (coreLoadExecutor-1455-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 387868 INFO (coreLoadExecutor-1455-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=21.7265625, floorSegmentMB=2.0634765625, forceMergeDeletesPctAllowed=9.750634810723144, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 387868 INFO (coreLoadExecutor-1455-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4a0192a[collection1] main]
[junit4] 2> 387869 INFO (coreLoadExecutor-1455-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 388136 INFO (coreLoadExecutor-1455-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 388691 INFO (coreLoadExecutor-1455-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 388692 INFO (coreLoadExecutor-1455-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1594690790039224320
[junit4] 2> 388692 INFO (searcherExecutor-1456-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 388693 INFO (searcherExecutor-1456-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 388693 INFO (searcherExecutor-1456-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 388693 INFO (searcherExecutor-1456-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 388694 INFO (searcherExecutor-1456-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 388695 INFO (searcherExecutor-1456-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 388697 INFO (searcherExecutor-1456-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 388701 INFO (searcherExecutor-1456-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 388702 INFO (searcherExecutor-1456-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 388703 INFO (searcherExecutor-1456-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4a0192a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 389203 INFO (TEST-TestDistributedSearch.test-seed#[8EED7088B1F7D439]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 389242 INFO (qtp1835097930-3573) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1594690790581338112)} 0 32
[junit4] 2> 389276 INFO (qtp1596657200-3590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1594690790634815488)} 0 16
[junit4] 2> 389306 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[1 (1594690790659981312)]} 0 22
[junit4] 2> 389333 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[1 (1594690790688292864)]} 0 21
[junit4] 2> 389349 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[2 (1594690790712410112)]} 0 14
[junit4] 2> 389356 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[2 (1594690790729187328)]} 0 5
[junit4] 2> 389358 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[3 (1594690790737575936)]} 0 0
[junit4] 2> 389375 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[3 (1594690790743867392)]} 0 11
[junit4] 2> 389379 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[4 (1594690790758547456)]} 0 0
[junit4] 2> 389380 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[4 (1594690790760644608)]} 0 0
[junit4] 2> 389381 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[5 (1594690790761693184)]} 0 0
[junit4] 2> 389383 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[5 (1594690790762741760)]} 0 0
[junit4] 2> 389384 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[6 (1594690790764838912)]} 0 0
[junit4] 2> 389386 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[6 (1594690790765887488)]} 0 0
[junit4] 2> 389404 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[7 (1594690790785810432)]} 0 0
[junit4] 2> 389406 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[7 (1594690790786859008)]} 0 0
[junit4] 2> 389408 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[8 (1594690790790004736)]} 0 0
[junit4] 2> 389409 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[8 (1594690790791053312)]} 0 0
[junit4] 2> 389410 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[9 (1594690790792101888)]} 0 0
[junit4] 2> 389411 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[9 (1594690790793150464)]} 0 0
[junit4] 2> 389412 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594690790794199040,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 389412 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a176402 commitCommandVersion:1594690790794199040
[junit4] 2> 389423 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d952433[collection1] main]
[junit4] 2> 389424 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 389426 INFO (searcherExecutor-1445-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2d952433[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C9)))}
[junit4] 2> 389426 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 14
[junit4] 2> 389427 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594690790809927680,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 389427 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@404d0cc9 commitCommandVersion:1594690790809927680
[junit4] 2> 389459 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@134c387b[collection1] main]
[junit4] 2> 389460 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 389462 INFO (searcherExecutor-1456-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@134c387b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C9)))}
[junit4] 2> 389462 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 35
[junit4] 2> 389465 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[10 (1594690790847676416)]} 0 1
[junit4] 2> 389467 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[10 (1594690790849773568)]} 0 1
[junit4] 2> 389469 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[11 (1594690790852919296)]} 0 1
[junit4] 2> 389479 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[11 (1594690790855016448)]} 0 9
[junit4] 2> 389481 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[12 (1594690790865502208)]} 0 0
[junit4] 2> 389482 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[12 (1594690790866550784)]} 0 0
[junit4] 2> 389483 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[13 (1594690790867599360)]} 0 0
[junit4] 2> 389484 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[13 (1594690790868647936)]} 0 0
[junit4] 2> 389485 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[1001 (1594690790869696512)]} 0 0
[junit4] 2> 389487 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[1001 (1594690790871793664)]} 0 0
[junit4] 2> 389488 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[14 (1594690790873890816)]} 0 0
[junit4] 2> 389489 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[14 (1594690790874939392)]} 0 0
[junit4] 2> 389490 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[15 (1594690790875987968)]} 0 0
[junit4] 2> 389491 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[15 (1594690790877036544)]} 0 0
[junit4] 2> 389493 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[16 (1594690790878085120)]} 0 0
[junit4] 2> 389494 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[16 (1594690790879133696)]} 0 0
[junit4] 2> 389495 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[17 (1594690790880182272)]} 0 0
[junit4] 2> 389496 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[17 (1594690790882279424)]} 0 0
[junit4] 2> 389497 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[100 (1594690790883328000)]} 0 0
[junit4] 2> 389498 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[100 (1594690790883328000)]} 0 0
[junit4] 2> 389498 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[101 (1594690790884376576)]} 0 0
[junit4] 2> 389501 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[101 (1594690790886473728)]} 0 1
[junit4] 2> 389512 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[102 (1594690790899056640)]} 0 0
[junit4] 2> 389524 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[102 (1594690790910590976)]} 0 0
[junit4] 2> 389525 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[103 (1594690790911639552)]} 0 0
[junit4] 2> 389525 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[103 (1594690790912688128)]} 0 0
[junit4] 2> 389527 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[104 (1594690790913736704)]} 0 0
[junit4] 2> 389532 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[104 (1594690790920028160)]} 0 0
[junit4] 2> 389533 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[105 (1594690790921076736)]} 0 0
[junit4] 2> 389534 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[105 (1594690790922125312)]} 0 0
[junit4] 2> 389535 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[106 (1594690790922125312)]} 0 0
[junit4] 2> 389536 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[106 (1594690790923173888)]} 0 0
[junit4] 2> 389537 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[107 (1594690790924222464)]} 0 0
[junit4] 2> 389538 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[107 (1594690790925271040)]} 0 0
[junit4] 2> 389539 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[108 (1594690790926319616)]} 0 0
[junit4] 2> 389540 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[108 (1594690790927368192)]} 0 0
[junit4] 2> 389541 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[109 (1594690790929465344)]} 0 0
[junit4] 2> 389542 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[109 (1594690790929465344)]} 0 0
[junit4] 2> 389543 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[110 (1594690790930513920)]} 0 0
[junit4] 2> 389543 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[110 (1594690790931562496)]} 0 0
[junit4] 2> 389544 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[111 (1594690790932611072)]} 0 0
[junit4] 2> 389545 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[111 (1594690790933659648)]} 0 0
[junit4] 2> 389546 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[112 (1594690790934708224)]} 0 0
[junit4] 2> 389547 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[112 (1594690790935756800)]} 0 0
[junit4] 2> 389548 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[113 (1594690790936805376)]} 0 0
[junit4] 2> 389549 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[113 (1594690790937853952)]} 0 0
[junit4] 2> 389550 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[114 (1594690790938902528)]} 0 0
[junit4] 2> 389551 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[114 (1594690790938902528)]} 0 0
[junit4] 2> 389552 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[115 (1594690790939951104)]} 0 0
[junit4] 2> 389553 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[115 (1594690790940999680)]} 0 0
[junit4] 2> 389554 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[116 (1594690790942048256)]} 0 0
[junit4] 2> 389555 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[116 (1594690790943096832)]} 0 0
[junit4] 2> 389556 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[117 (1594690790945193984)]} 0 0
[junit4] 2> 389557 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[117 (1594690790946242560)]} 0 0
[junit4] 2> 389560 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[118 (1594690790949388288)]} 0 0
[junit4] 2> 389561 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[118 (1594690790950436864)]} 0 0
[junit4] 2> 389562 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[119 (1594690790951485440)]} 0 0
[junit4] 2> 389565 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[119 (1594690790954631168)]} 0 0
[junit4] 2> 389571 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[120 (1594690790959874048)]} 0 0
[junit4] 2> 389574 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[120 (1594690790963019776)]} 0 0
[junit4] 2> 389575 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[121 (1594690790964068352)]} 0 0
[junit4] 2> 389576 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[121 (1594690790965116928)]} 0 0
[junit4] 2> 389576 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[122 (1594690790966165504)]} 0 0
[junit4] 2> 389577 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[122 (1594690790967214080)]} 0 0
[junit4] 2> 389578 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[123 (1594690790968262656)]} 0 0
[junit4] 2> 389586 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[123 (1594690790976651264)]} 0 0
[junit4] 2> 389588 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[124 (1594690790978748416)]} 0 0
[junit4] 2> 389589 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[124 (1594690790979796992)]} 0 0
[junit4] 2> 389590 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[125 (1594690790980845568)]} 0 0
[junit4] 2> 389591 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[125 (1594690790981894144)]} 0 0
[junit4] 2> 389592 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[126 (1594690790982942720)]} 0 0
[junit4] 2> 389593 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[126 (1594690790982942720)]} 0 0
[junit4] 2> 389594 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[127 (1594690790983991296)]} 0 0
[junit4] 2> 389594 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[127 (1594690790985039872)]} 0 0
[junit4] 2> 389595 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[128 (1594690790986088448)]} 0 0
[junit4] 2> 389596 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[128 (1594690790987137024)]} 0 0
[junit4] 2> 389597 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[129 (1594690790988185600)]} 0 0
[junit4] 2> 389598 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[129 (1594690790989234176)]} 0 0
[junit4] 2> 389599 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[130 (1594690790990282752)]} 0 0
[junit4] 2> 389600 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[130 (1594690790990282752)]} 0 0
[junit4] 2> 389600 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[131 (1594690790991331328)]} 0 0
[junit4] 2> 389601 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[131 (1594690790992379904)]} 0 0
[junit4] 2> 389602 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[132 (1594690790993428480)]} 0 0
[junit4] 2> 389603 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[132 (1594690790994477056)]} 0 0
[junit4] 2> 389604 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[133 (1594690790995525632)]} 0 0
[junit4] 2> 389605 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[133 (1594690790995525632)]} 0 0
[junit4] 2> 389605 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[134 (1594690790996574208)]} 0 0
[junit4] 2> 389606 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[134 (1594690790997622784)]} 0 0
[junit4] 2> 389607 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[135 (1594690790998671360)]} 0 0
[junit4] 2> 389608 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[135 (1594690790999719936)]} 0 0
[junit4] 2> 389609 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[136 (1594690790999719936)]} 0 0
[junit4] 2> 389609 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[136 (1594690791000768512)]} 0 0
[junit4] 2> 389610 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[137 (1594690791001817088)]} 0 0
[junit4] 2> 389611 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[137 (1594690791002865664)]} 0 0
[junit4] 2> 389612 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[138 (1594690791003914240)]} 0 0
[junit4] 2> 389613 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[138 (1594690791003914240)]} 0 0
[junit4] 2> 389614 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[139 (1594690791004962816)]} 0 0
[junit4] 2> 389614 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[139 (1594690791006011392)]} 0 0
[junit4] 2> 389615 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[140 (1594690791007059968)]} 0 0
[junit4] 2> 389616 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[140 (1594690791008108544)]} 0 0
[junit4] 2> 389617 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[141 (1594690791009157120)]} 0 0
[junit4] 2> 389618 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[141 (1594690791010205696)]} 0 0
[junit4] 2> 389619 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[142 (1594690791011254272)]} 0 0
[junit4] 2> 389620 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[142 (1594690791012302848)]} 0 0
[junit4] 2> 389621 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[143 (1594690791013351424)]} 0 0
[junit4] 2> 389622 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[143 (1594690791014400000)]} 0 0
[junit4] 2> 389623 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[144 (1594690791015448576)]} 0 0
[junit4] 2> 389624 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[144 (1594690791016497152)]} 0 0
[junit4] 2> 389626 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[145 (1594690791017545728)]} 0 0
[junit4] 2> 389626 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[145 (1594690791018594304)]} 0 0
[junit4] 2> 389631 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[146 (1594690791022788608)]} 0 1
[junit4] 2> 389634 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[146 (1594690791025934336)]} 0 0
[junit4] 2> 389640 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[147 (1594690791033274368)]} 0 0
[junit4] 2> 389642 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[147 (1594690791035371520)]} 0 1
[junit4] 2> 389644 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[148 (1594690791037468672)]} 0 0
[junit4] 2> 389646 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[148 (1594690791039565824)]} 0 0
[junit4] 2> 389653 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[149 (1594690791044808704)]} 0 2
[junit4] 2> 389655 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={wt=javabin&version=2}{add=[149 (1594690791049003008)]} 0 1
[junit4] 2> 389657 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594690791051100160,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 389657 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6a176402 commitCommandVersion:1594690791051100160
[junit4] 2> 389713 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@585a9a32[collection1] main]
[junit4] 2> 389716 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 389721 INFO (searcherExecutor-1445-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@585a9a32[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C9) Uninverting(_1(7.3.0):C59)))}
[junit4] 2> 389721 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 64
[junit4] 2> 389722 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594690791119257600,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 389722 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@404d0cc9 commitCommandVersion:1594690791119257600
[junit4] 2> 389742 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@43aa536f[collection1] main]
[junit4] 2> 389743 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 389744 INFO (searcherExecutor-1456-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@43aa536f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C9) Uninverting(_1(7.3.0):C59)))}
[junit4] 2> 389745 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_m path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 22
[junit4] 2> 389746 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 389746 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 389747 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 389747 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 389749 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 389766 INFO (qtp1596657200-3596) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::114]:33332/_m|http://127.0.0.1:49588/_m/collection1&rows=10&version=2&q=*:*&NOW=1520815650129&isShard=true&wt=javabin} hits=68 status=0 QTime=7
[junit4] 2> 389769 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520815650129&ids=12,123,134,135,125,15,114,148,130,120&isShard=true&shard.url=[ff01::114]:33332/_m|http://127.0.0.1:49588/_m/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 389777 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&shards=[ff01::114]:33332/_m|http://127.0.0.1:49588/_m/collection1&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=25
[junit4] 2> 389780 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 389784 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=http://127.0.0.1:49588/_m/collection1|[ff01::083]:33332/_m|[ff01::114]:33332/_m|[ff01::213]:33332/_m&rows=10&version=2&q=*:*&NOW=1520815650160&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 389786 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520815650160&ids=1,13,14,103,136,7,109,140,141,10&isShard=true&shard.url=http://127.0.0.1:49588/_m/collection1|[ff01::083]:33332/_m|[ff01::114]:33332/_m|[ff01::213]:33332/_m&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 389819 INFO (qtp1596657200-3596) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&shards=http://127.0.0.1:49588/_m/collection1|[ff01::083]:33332/_m|[ff01::114]:33332/_m|[ff01::213]:33332/_m&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=36
[junit4] 2> 389828 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 389843 INFO (qtp1596657200-3596) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::213]:33332/_m|[ff01::083]:33332/_m|http://127.0.0.1:49588/_m/collection1|[ff01::114]:33332/_m&rows=10&version=2&q=*:*&NOW=1520815650213&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 389849 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520815650213&ids=110,121,144,113,14,3,15,114,137,128&isShard=true&shard.url=[ff01::213]:33332/_m|[ff01::083]:33332/_m|http://127.0.0.1:49588/_m/collection1|[ff01::114]:33332/_m&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 389882 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&shards=[ff01::213]:33332/_m|[ff01::083]:33332/_m|http://127.0.0.1:49588/_m/collection1|[ff01::114]:33332/_m&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=46
[junit4] 2> 389888 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 389895 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=http://127.0.0.1:49588/_m/collection1|[ff01::114]:33332/_m|[ff01::083]:33332/_m&rows=10&version=2&q=*:*&NOW=1520815650271&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 389899 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520815650271&ids=143,12,101,103,147,127,116,17,1001,106&isShard=true&shard.url=http://127.0.0.1:49588/_m/collection1|[ff01::114]:33332/_m|[ff01::083]:33332/_m&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 389928 INFO (qtp1596657200-3596) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&shards=http://127.0.0.1:49588/_m/collection1|[ff01::114]:33332/_m|[ff01::083]:33332/_m&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=34
[junit4] 2> 389934 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 389940 INFO (qtp1596657200-3596) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=[ff01::114]:33332/_m|http://127.0.0.1:49588/_m/collection1|[ff01::083]:33332/_m&rows=10&version=2&q=*:*&NOW=1520815650317&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 389942 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520815650317&ids=11,133,145,2,135,149,17,106,109,10&isShard=true&shard.url=[ff01::114]:33332/_m|http://127.0.0.1:49588/_m/collection1|[ff01::083]:33332/_m&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 389952 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&shards=[ff01::114]:33332/_m|http://127.0.0.1:49588/_m/collection1|[ff01::083]:33332/_m&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=12
[junit4] 2> 389953 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 389956 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[ff01::083]:33332/_m|http://127.0.0.1:49588/_m/collection1|[ff01::114]:33332/_m&rows=10&version=2&q=*:*&NOW=1520815650332&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 389957 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520815650332&ids=143,132,102,4,103,137,5,115,105,108&isShard=true&shard.url=[ff01::083]:33332/_m|http://127.0.0.1:49588/_m/collection1|[ff01::114]:33332/_m&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 389958 INFO (qtp1596657200-3596) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&shards=[ff01::083]:33332/_m|http://127.0.0.1:49588/_m/collection1|[ff01::114]:33332/_m&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 389960 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 389963 INFO (qtp1596657200-3596) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=http://127.0.0.1:49588/_m/collection1&rows=10&version=2&q=*:*&NOW=1520815650339&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 389965 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520815650339&ids=110,1,144,101,112,6,17,106,118,130&isShard=true&shard.url=http://127.0.0.1:49588/_m/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 389966 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&shards=http://127.0.0.1:49588/_m/collection1&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 389970 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 389973 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=[ff01::083]:33332/_m|http://127.0.0.1:49588/_m/collection1|[ff01::213]:33332/_m|[ff01::114]:33332/_m&rows=10&version=2&q=*:*&NOW=1520815650349&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 389977 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520815650349&ids=11,121,122,111,4,104,16,126,117,107&isShard=true&shard.url=[ff01::083]:33332/_m|http://127.0.0.1:49588/_m/collection1|[ff01::213]:33332/_m|[ff01::114]:33332/_m&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 389978 INFO (qtp1596657200-3596) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&shards=[ff01::083]:33332/_m|http://127.0.0.1:49588/_m/collection1|[ff01::213]:33332/_m|[ff01::114]:33332/_m&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 389979 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 389981 INFO (qtp1596657200-3596) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=[ff01::213]:33332/_m|http://127.0.0.1:49588/_m/collection1|[ff01::114]:33332/_m&rows=10&version=2&q=*:*&NOW=1520815650358&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 389983 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520815650358&ids=110,133,123,101,148,126,16,1001,140,130&isShard=true&shard.url=[ff01::213]:33332/_m|http://127.0.0.1:49588/_m/collection1|[ff01::114]:33332/_m&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 389984 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&shards=[ff01::213]:33332/_m|http://127.0.0.1:49588/_m/collection1|[ff01::114]:33332/_m&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 389985 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 389990 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=http://127.0.0.1:49588/_m/collection1&rows=10&version=2&q=*:*&NOW=1520815650364&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 390001 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520815650364&ids=145,135,147,117,106,139,118,107,8,108&isShard=true&shard.url=http://127.0.0.1:49588/_m/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 390002 INFO (qtp1596657200-3596) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&shards=http://127.0.0.1:49588/_m/collection1&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=15
[junit4] 2> 390004 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 390009 INFO (qtp1596657200-3596) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=http://127.0.0.1:49588/_m/collection1|[ff01::114]:33332/_m|[ff01::083]:33332/_m|[ff01::213]:33332/_m&rows=10&version=2&q=*:*&NOW=1520815650383&isShard=true&wt=javabin} hits=68 status=0 QTime=3
[junit4] 2> 390016 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520815650383&ids=2,126,149,105,127,118,8,129,130,131&isShard=true&shard.url=http://127.0.0.1:49588/_m/collection1|[ff01::114]:33332/_m|[ff01::083]:33332/_m|[ff01::213]:33332/_m&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 390017 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&shards=http://127.0.0.1:49588/_m/collection1|[ff01::114]:33332/_m|[ff01::083]:33332/_m|[ff01::213]:33332/_m&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=12
[junit4] 2> 390019 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 390025 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=[ff01::114]:33332/_m|[ff01::083]:33332/_m|http://127.0.0.1:49588/_m/collection1|[ff01::213]:33332/_m&rows=10&version=2&q=*:*&NOW=1520815650399&isShard=true&wt=javabin} hits=68 status=0 QTime=2
[junit4] 2> 390028 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520815650399&ids=121,11,110,111,100,135,124,136,5,17&isShard=true&shard.url=[ff01::114]:33332/_m|[ff01::083]:33332/_m|http://127.0.0.1:49588/_m/collection1|[ff01::213]:33332/_m&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 390029 INFO (qtp1596657200-3596) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&shards=[ff01::114]:33332/_m|[ff01::083]:33332/_m|http://127.0.0.1:49588/_m/collection1|[ff01::213]:33332/_m&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=7
[junit4] 2> 390030 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 390033 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=[ff01::213]:33332/_m|http://127.0.0.1:49588/_m/collection1&rows=10&version=2&q=*:*&NOW=1520815650410&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 390034 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520815650410&ids=132,144,100,112,113,4,137,149,129,141&isShard=true&shard.url=[ff01::213]:33332/_m|http://127.0.0.1:49588/_m/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 390036 INFO (qtp1596657200-3597) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&shards=[ff01::213]:33332/_m|http://127.0.0.1:49588/_m/collection1&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 390037 INFO (qtp1835097930-3575) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 390041 INFO (qtp1596657200-3597) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=[ff01::083]:33332/_m|[ff01::213]:33332/_m|[ff01::114]:33332/_m|http://127.0.0.1:49588/_m/collection1&rows=10&version=2&q=*:*&NOW=1520815650418&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 390042 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520815650418&ids=11,121,143,12,115,5,148,17,10,120&isShard=true&shard.url=[ff01::083]:33332/_m|[ff01::213]:33332/_m|[ff01::114]:33332/_m|http://127.0.0.1:49588/_m/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 390043 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&shards=[ff01::083]:33332/_m|[ff01::213]:33332/_m|[ff01::114]:33332/_m|http://127.0.0.1:49588/_m/collection1&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 390045 INFO (qtp1835097930-3577) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 390053 INFO (qtp1596657200-3592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=[ff01::083]:33332/_m|http://127.0.0.1:49588/_m/collection1&rows=10&version=2&q=*:*&NOW=1520815650430&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 390054 INFO (qtp1596657200-3594) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520815650430&ids=144,2,147,15,149,138,1001,129,130,131&isShard=true&shard.url=[ff01::083]:33332/_m|http://127.0.0.1:49588/_m/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 390055 INFO (qtp1596657200-3597) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_m path=/select params={q=*:*&shards=[ff01::083]:33332/_m|http://127.0.0.1:49588/_m/collection1&sort=n_dt1+desc&wt=javabin&version=2
[...truncated too long message...]
eRequestBody(SearchHandler.java:423)
[junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:195)
[junit4] > at org.apache.solr.core.SolrCore.execute(SolrCore.java:2503)
[junit4] > at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:711)
[junit4] > at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:517)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:384)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:330)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
[junit4] > at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] > at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:527)
[junit4] > at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] > at org.eclipse.jetty.server.Server.handle(Server.java:530)
[junit4] > at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347)
[junit4] > at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256)
[junit4] > at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
[junit4] > at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] > at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
[junit4] > at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)
[junit4] > at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)
[junit4] > at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
[junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:382)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] > at __randomizedtesting.SeedInfo.seed([8EED7088B1F7D439:6B94F521F0BB9C1]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:957)
[junit4] > at org.apache.solr.TestDistributedSearch.queryRandomUpServer(TestDistributedSearch.java:1168)
[junit4] > at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1130)
[junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:992)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1019)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_8EED7088B1F7D439-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {severity=FST50, foo_sev_enum=PostingsFormat(name=Memory), SubjectTerms_mfacet=PostingsFormat(name=Memory), multiDefault=Lucene50(blocksize=128), rnd_b=PostingsFormat(name=Memory), a_s=FST50, oddField_s=FST50, lowerfilt1and2=Lucene50(blocksize=128), a_t=PostingsFormat(name=Memory), foo_b=FST50, lowerfilt=Lucene50(blocksize=128), id=PostingsFormat(name=Memory), text=FST50}, docValues:{other_tl1=DocValuesFormat(name=Lucene70), range_facet_l_dv=DocValuesFormat(name=Direct), n_l1=DocValuesFormat(name=Lucene70), a_n_tdt=DocValuesFormat(name=Direct), intDefault=DocValuesFormat(name=Lucene70), n_td1=DocValuesFormat(name=Asserting), n_d1=DocValuesFormat(name=Lucene70), range_facet_l=DocValuesFormat(name=Lucene70), n_f1=DocValuesFormat(name=Asserting), n_tl1=DocValuesFormat(name=Asserting), n_tf1=DocValuesFormat(name=Lucene70), intDvoDefault=DocValuesFormat(name=Lucene70), timestamp=DocValuesFormat(name=Lucene70), severity=DocValuesFormat(name=Lucene70), foo_sev_enum=DocValuesFormat(name=Direct), n_dt1=DocValuesFormat(name=Asserting), b_n_tdt=DocValuesFormat(name=Lucene70), n_ti1=DocValuesFormat(name=Direct), _version_=DocValuesFormat(name=Lucene70), n_tdt1=DocValuesFormat(name=Lucene70), id_i1=DocValuesFormat(name=Asserting), foo_d=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Lucene70), foo_f=DocValuesFormat(name=Lucene70), a_i_ni_p=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1612, maxMBSortInHeap=6.588822044276961, sim=RandomSimilarity(queryNorm=true): {}, locale=nl-NL, timezone=Pacific/Tongatapu
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 9 (64-bit)/cpus=3,threads=2,free=63055328,total=244785152
[junit4] 2> NOTE: All tests run in this JVM: [TestCustomDocTransformer, TestRandomFaceting, CdcrRequestHandlerTest, TestSolr4Spatial, TestLocalFSCloudBackupRestore, DeleteLastCustomShardedReplicaTest, ReplicationFactorTest, MetricsHandlerTest, TestJsonFacetRefinement, NoCacheHeaderTest, TestCrossCoreJoin, TestDFRSimilarityFactory, TestRestManager, CurrencyRangeFacetCloudTest, TestQuerySenderListener, DocExpirationUpdateProcessorFactoryTest, TestRequestForwarding, TestRandomRequestDistribution, DirectUpdateHandlerTest, TimeZoneUtilsTest, TestDynamicFieldResource, TestCodecSupport, ZkControllerTest, DistributedQueryComponentCustomSortTest, TestRequestStatusCollectionAPI, RankQueryTest, BufferStoreTest, TestTrackingShardHandlerFactory, TestExactStatsCache, TestRetrieveFieldsOptimizer, DocValuesNotIndexedTest, TestDocSet, TestHdfsBackupRestoreCore, SuggestComponentContextFilterQueryTest, SpellCheckComponentTest, V2StandaloneTest, ClusterStateTest, BlockJoinFacetRandomTest, TestSearchPerf, ParsingFieldUpdateProcessorsTest, RecoveryAfterSoftCommitTest, ConcurrentCreateRoutedAliasTest, TestHalfAndHalfDocValues, TestFilteredDocIdSet, SolrMetricManagerTest, TestFastWriter, TestDocumentBuilder, TestClassicSimilarityFactory, ConfigureRecoveryStrategyTest, DefaultValueUpdateProcessorTest, TestSolrCloudSnapshots, TestInfoStreamLogging, LIROnShardRestartTest, SaslZkACLProviderTest, TestCloudPivotFacet, IndexSchemaTest, TokenizerChainTest, TestDistributedSearch]
[junit4] Completed [101/783 (1!)] on J1 in 87.03s, 1 test, 1 error <<< FAILURES!
[...truncated 45070 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/501/consoleText
[repro] Revision: d77f618a672d719b971684c55b9fa07d0ba59aaf
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=8EED7088B1F7D439 -Dtests.slow=true -Dtests.locale=nl-NL -Dtests.timezone=Pacific/Tongatapu -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestDistributedSearch
[repro] ant compile-test
[...truncated 3336 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestDistributedSearch" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=8EED7088B1F7D439 -Dtests.slow=true -Dtests.locale=nl-NL -Dtests.timezone=Pacific/Tongatapu -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 79 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.TestDistributedSearch
[repro] Exiting with code 0
[...truncated 70 lines...]