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/07/06 20:30:54 UTC
[JENKINS] Lucene-Solr-7.x-Linux (32bit/jdk1.8.0_172) - Build # 2273
- Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/2273/
Java: 32bit/jdk1.8.0_172 -client -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.TestDistributedSearch.test
Error Message:
Expected to find shardAddress in the up shard info: {error=org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded,trace=org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:460) at org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java:279) at org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:175) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) ,time=12}
Stack Trace:
java.lang.AssertionError: Expected to find shardAddress in the up shard info: {error=org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded,trace=org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:460)
at org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java:279)
at org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:175)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
,time=12}
at __randomizedtesting.SeedInfo.seed([CE529A2E7A9C3BDA:4606A5F4D4605622]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1192)
at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1133)
at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:993)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1034)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:983)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13869 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> 1555261 INFO (SUITE-TestDistributedSearch-seed#[CE529A2E7A9C3BDA]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_CE529A2E7A9C3BDA-001/init-core-data-001
[junit4] 2> 1555261 WARN (SUITE-TestDistributedSearch-seed#[CE529A2E7A9C3BDA]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 1555262 INFO (SUITE-TestDistributedSearch-seed#[CE529A2E7A9C3BDA]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1555263 INFO (SUITE-TestDistributedSearch-seed#[CE529A2E7A9C3BDA]-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)
[junit4] 2> 1555263 INFO (SUITE-TestDistributedSearch-seed#[CE529A2E7A9C3BDA]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /jhlig/m
[junit4] 2> 1555364 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_CE529A2E7A9C3BDA-001/tempDir-001/control/cores/collection1
[junit4] 2> 1555366 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 1555367 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1555367 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1555367 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1555368 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3873ad{/jhlig/m,null,AVAILABLE}
[junit4] 2> 1555383 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1b4cb09{HTTP/1.1,[http/1.1]}{127.0.0.1:43311}
[junit4] 2> 1555383 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.e.j.s.Server Started @1555421ms
[junit4] 2> 1555384 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/jhlig/m, hostPort=43311, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_CE529A2E7A9C3BDA-001/tempDir-001/control/cores}
[junit4] 2> 1555384 ERROR (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1555384 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1555384 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.5.0
[junit4] 2> 1555384 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1555384 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1555384 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-06T19:43:55.686Z
[junit4] 2> 1555385 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_CE529A2E7A9C3BDA-001/tempDir-001/control/solr.xml
[junit4] 2> 1555389 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1555389 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1555391 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@97ade7, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1557233 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1557233 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1557248 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@97ade7
[junit4] 2> 1557258 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@97ade7
[junit4] 2> 1557258 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@97ade7
[junit4] 2> 1557260 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_CE529A2E7A9C3BDA-001/tempDir-001/control/cores
[junit4] 2> 1557260 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1557266 INFO (coreLoadExecutor-4735-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1557308 INFO (coreLoadExecutor-4735-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 1557343 INFO (coreLoadExecutor-4735-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1557437 INFO (coreLoadExecutor-4735-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1557447 INFO (coreLoadExecutor-4735-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1557448 INFO (coreLoadExecutor-4735-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@97ade7
[junit4] 2> 1557448 INFO (coreLoadExecutor-4735-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1557448 INFO (coreLoadExecutor-4735-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_CE529A2E7A9C3BDA-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 1557451 INFO (coreLoadExecutor-4735-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1557518 INFO (coreLoadExecutor-4735-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1557518 INFO (coreLoadExecutor-4735-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1557519 INFO (coreLoadExecutor-4735-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1557519 INFO (coreLoadExecutor-4735-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1557520 INFO (coreLoadExecutor-4735-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1679387207, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1557521 INFO (coreLoadExecutor-4735-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@d5616c[collection1] main]
[junit4] 2> 1557522 INFO (coreLoadExecutor-4735-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1557543 INFO (coreLoadExecutor-4735-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1557630 INFO (coreLoadExecutor-4735-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1557630 INFO (coreLoadExecutor-4735-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1605271539345784832
[junit4] 2> 1557630 INFO (searcherExecutor-4736-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1557631 INFO (searcherExecutor-4736-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1557631 INFO (searcherExecutor-4736-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1557631 INFO (searcherExecutor-4736-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1557631 INFO (searcherExecutor-4736-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1557631 INFO (searcherExecutor-4736-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1557632 INFO (searcherExecutor-4736-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1557632 INFO (searcherExecutor-4736-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1557632 INFO (searcherExecutor-4736-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1557634 INFO (searcherExecutor-4736-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@d5616c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1557732 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_CE529A2E7A9C3BDA-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 1557733 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 1557737 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1557737 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1557737 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1557737 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@34a893{/jhlig/m,null,AVAILABLE}
[junit4] 2> 1557738 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1c19ba{HTTP/1.1,[http/1.1]}{127.0.0.1:44037}
[junit4] 2> 1557738 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.e.j.s.Server Started @1557775ms
[junit4] 2> 1557738 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/jhlig/m, hostPort=44037, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_CE529A2E7A9C3BDA-001/tempDir-001/shard0/cores}
[junit4] 2> 1557738 ERROR (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1557738 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1557738 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.5.0
[junit4] 2> 1557738 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1557738 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1557738 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-06T19:43:58.040Z
[junit4] 2> 1557739 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_CE529A2E7A9C3BDA-001/tempDir-001/shard0/solr.xml
[junit4] 2> 1557742 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1557742 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1557743 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@97ade7, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1559524 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1559525 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1559549 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@97ade7
[junit4] 2> 1559576 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@97ade7
[junit4] 2> 1559577 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@97ade7
[junit4] 2> 1559579 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_CE529A2E7A9C3BDA-001/tempDir-001/shard0/cores
[junit4] 2> 1559579 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1559579 INFO (coreLoadExecutor-4749-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1559611 INFO (coreLoadExecutor-4749-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 1559631 INFO (coreLoadExecutor-4749-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1559744 INFO (coreLoadExecutor-4749-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1559756 INFO (coreLoadExecutor-4749-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1559756 INFO (coreLoadExecutor-4749-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@97ade7
[junit4] 2> 1559757 INFO (coreLoadExecutor-4749-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1559757 INFO (coreLoadExecutor-4749-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_CE529A2E7A9C3BDA-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 1559759 INFO (coreLoadExecutor-4749-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1559819 INFO (coreLoadExecutor-4749-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1559819 INFO (coreLoadExecutor-4749-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1559820 INFO (coreLoadExecutor-4749-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1559820 INFO (coreLoadExecutor-4749-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1559821 INFO (coreLoadExecutor-4749-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1679387207, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1559822 INFO (coreLoadExecutor-4749-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f8038a[collection1] main]
[junit4] 2> 1559823 INFO (coreLoadExecutor-4749-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1559841 INFO (coreLoadExecutor-4749-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1559925 INFO (coreLoadExecutor-4749-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1559925 INFO (searcherExecutor-4750-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1559925 INFO (coreLoadExecutor-4749-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1605271541752266752
[junit4] 2> 1559925 INFO (searcherExecutor-4750-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1559925 INFO (searcherExecutor-4750-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1559925 INFO (searcherExecutor-4750-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1559926 INFO (searcherExecutor-4750-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1559926 INFO (searcherExecutor-4750-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1559926 INFO (searcherExecutor-4750-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1559926 INFO (searcherExecutor-4750-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1559927 INFO (searcherExecutor-4750-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1559928 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1559929 INFO (searcherExecutor-4750-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1f8038a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1559932 INFO (qtp12396988-14966) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1605271541757509632)} 0 1
[junit4] 2> 1559936 INFO (qtp32750226-14986) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1605271541761703936)} 0 1
[junit4] 2> 1559940 INFO (qtp12396988-14960) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[1 (1605271541765898240)]} 0 2
[junit4] 2> 1559960 INFO (qtp32750226-14980) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[1 (1605271541769043968)]} 0 19
[junit4] 2> 1559963 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[2 (1605271541791064064)]} 0 1
[junit4] 2> 1559965 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[2 (1605271541793161216)]} 0 1
[junit4] 2> 1559968 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[3 (1605271541796306944)]} 0 1
[junit4] 2> 1559970 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[3 (1605271541798404096)]} 0 1
[junit4] 2> 1559973 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[4 (1605271541801549824)]} 0 1
[junit4] 2> 1559975 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[4 (1605271541803646976)]} 0 1
[junit4] 2> 1559978 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[5 (1605271541806792704)]} 0 1
[junit4] 2> 1559980 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[5 (1605271541808889856)]} 0 1
[junit4] 2> 1559983 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[6 (1605271541812035584)]} 0 1
[junit4] 2> 1559985 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[6 (1605271541814132736)]} 0 0
[junit4] 2> 1559987 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[7 (1605271541816229888)]} 0 1
[junit4] 2> 1559989 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[7 (1605271541818327040)]} 0 1
[junit4] 2> 1559991 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[8 (1605271541820424192)]} 0 1
[junit4] 2> 1559993 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[8 (1605271541822521344)]} 0 1
[junit4] 2> 1559996 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[9 (1605271541825667072)]} 0 1
[junit4] 2> 1559998 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[9 (1605271541827764224)]} 0 0
[junit4] 2> 1559999 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1605271541829861376,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1559999 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@15408ed commitCommandVersion:1605271541829861376
[junit4] 2> 1560014 INFO (qtp12396988-14965) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@11db65b[collection1] main]
[junit4] 2> 1560015 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1560017 INFO (searcherExecutor-4736-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@11db65b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.5.0):C9)))}
[junit4] 2> 1560018 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 19
[junit4] 2> 1560020 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1605271541851881472,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1560020 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1341aaa commitCommandVersion:1605271541851881472
[junit4] 2> 1560031 INFO (qtp32750226-14985) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@210d34[collection1] main]
[junit4] 2> 1560031 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1560033 INFO (searcherExecutor-4750-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@210d34[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.5.0):C9)))}
[junit4] 2> 1560033 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 13
[junit4] 2> 1560037 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[10 (1605271541867610112)]} 0 3
[junit4] 2> 1560041 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[10 (1605271541871804416)]} 0 2
[junit4] 2> 1560043 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[11 (1605271541875998720)]} 0 1
[junit4] 2> 1560045 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[11 (1605271541878095872)]} 0 1
[junit4] 2> 1560047 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[12 (1605271541880193024)]} 0 0
[junit4] 2> 1560049 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[12 (1605271541881241600)]} 0 0
[junit4] 2> 1560051 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[13 (1605271541883338752)]} 0 0
[junit4] 2> 1560053 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[13 (1605271541885435904)]} 0 0
[junit4] 2> 1560055 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[1001 (1605271541887533056)]} 0 1
[junit4] 2> 1560057 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[1001 (1605271541889630208)]} 0 1
[junit4] 2> 1560060 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[14 (1605271541892775936)]} 0 1
[junit4] 2> 1560062 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[14 (1605271541894873088)]} 0 0
[junit4] 2> 1560063 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[15 (1605271541896970240)]} 0 0
[junit4] 2> 1560065 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[15 (1605271541898018816)]} 0 0
[junit4] 2> 1560067 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[16 (1605271541900115968)]} 0 0
[junit4] 2> 1560068 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[16 (1605271541902213120)]} 0 0
[junit4] 2> 1560070 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[17 (1605271541903261696)]} 0 1
[junit4] 2> 1560073 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[17 (1605271541906407424)]} 0 1
[junit4] 2> 1560074 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[100 (1605271541908504576)]} 0 0
[junit4] 2> 1560076 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[100 (1605271541910601728)]} 0 0
[junit4] 2> 1560078 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[101 (1605271541911650304)]} 0 0
[junit4] 2> 1560080 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[101 (1605271541913747456)]} 0 0
[junit4] 2> 1560082 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[102 (1605271541915844608)]} 0 0
[junit4] 2> 1560084 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[102 (1605271541917941760)]} 0 1
[junit4] 2> 1560086 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[103 (1605271541920038912)]} 0 0
[junit4] 2> 1560088 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[103 (1605271541922136064)]} 0 0
[junit4] 2> 1560090 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[104 (1605271541925281792)]} 0 0
[junit4] 2> 1560092 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[104 (1605271541927378944)]} 0 0
[junit4] 2> 1560094 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[105 (1605271541929476096)]} 0 0
[junit4] 2> 1560096 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[105 (1605271541930524672)]} 0 0
[junit4] 2> 1560100 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[106 (1605271541934718976)]} 0 0
[junit4] 2> 1560102 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[106 (1605271541936816128)]} 0 1
[junit4] 2> 1560104 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[107 (1605271541939961856)]} 0 0
[junit4] 2> 1560106 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[107 (1605271541941010432)]} 0 0
[junit4] 2> 1560108 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[108 (1605271541944156160)]} 0 0
[junit4] 2> 1560110 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[108 (1605271541945204736)]} 0 0
[junit4] 2> 1560112 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[109 (1605271541947301888)]} 0 0
[junit4] 2> 1560114 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[109 (1605271541949399040)]} 0 0
[junit4] 2> 1560116 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[110 (1605271541951496192)]} 0 0
[junit4] 2> 1560118 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[110 (1605271541953593344)]} 0 0
[junit4] 2> 1560125 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[111 (1605271541960933376)]} 0 1
[junit4] 2> 1560128 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[111 (1605271541964079104)]} 0 0
[junit4] 2> 1560130 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[112 (1605271541966176256)]} 0 1
[junit4] 2> 1560132 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[112 (1605271541968273408)]} 0 0
[junit4] 2> 1560134 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[113 (1605271541971419136)]} 0 1
[junit4] 2> 1560137 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[113 (1605271541973516288)]} 0 0
[junit4] 2> 1560139 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[114 (1605271541975613440)]} 0 1
[junit4] 2> 1560141 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[114 (1605271541978759168)]} 0 0
[junit4] 2> 1560144 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[115 (1605271541980856320)]} 0 1
[junit4] 2> 1560146 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[115 (1605271541982953472)]} 0 0
[junit4] 2> 1560148 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[116 (1605271541985050624)]} 0 1
[junit4] 2> 1560150 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[116 (1605271541988196352)]} 0 0
[junit4] 2> 1560152 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[117 (1605271541989244928)]} 0 0
[junit4] 2> 1560154 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[117 (1605271541991342080)]} 0 0
[junit4] 2> 1560156 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[118 (1605271541993439232)]} 0 0
[junit4] 2> 1560158 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[118 (1605271541995536384)]} 0 0
[junit4] 2> 1560160 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[119 (1605271541997633536)]} 0 0
[junit4] 2> 1560162 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[119 (1605271541999730688)]} 0 0
[junit4] 2> 1560164 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[120 (1605271542001827840)]} 0 0
[junit4] 2> 1560165 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[120 (1605271542003924992)]} 0 0
[junit4] 2> 1560168 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[121 (1605271542006022144)]} 0 0
[junit4] 2> 1560171 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[121 (1605271542008119296)]} 0 1
[junit4] 2> 1560173 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[122 (1605271542011265024)]} 0 0
[junit4] 2> 1560174 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[122 (1605271542013362176)]} 0 0
[junit4] 2> 1560176 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[123 (1605271542014410752)]} 0 0
[junit4] 2> 1560178 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[123 (1605271542016507904)]} 0 1
[junit4] 2> 1560179 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[124 (1605271542018605056)]} 0 0
[junit4] 2> 1560182 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[124 (1605271542020702208)]} 0 0
[junit4] 2> 1560183 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[125 (1605271542022799360)]} 0 0
[junit4] 2> 1560184 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[125 (1605271542023847936)]} 0 0
[junit4] 2> 1560186 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[126 (1605271542025945088)]} 0 0
[junit4] 2> 1560188 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[126 (1605271542026993664)]} 0 0
[junit4] 2> 1560190 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[127 (1605271542029090816)]} 0 0
[junit4] 2> 1560192 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[127 (1605271542031187968)]} 0 0
[junit4] 2> 1560193 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[128 (1605271542033285120)]} 0 0
[junit4] 2> 1560195 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[128 (1605271542034333696)]} 0 0
[junit4] 2> 1560196 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[129 (1605271542036430848)]} 0 0
[junit4] 2> 1560198 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[129 (1605271542038528000)]} 0 0
[junit4] 2> 1560200 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[130 (1605271542040625152)]} 0 0
[junit4] 2> 1560202 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[130 (1605271542042722304)]} 0 0
[junit4] 2> 1560203 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[131 (1605271542043770880)]} 0 0
[junit4] 2> 1560205 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[131 (1605271542044819456)]} 0 0
[junit4] 2> 1560207 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[132 (1605271542046916608)]} 0 0
[junit4] 2> 1560208 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[132 (1605271542049013760)]} 0 0
[junit4] 2> 1560210 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[133 (1605271542051110912)]} 0 0
[junit4] 2> 1560212 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[133 (1605271542052159488)]} 0 0
[junit4] 2> 1560213 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[134 (1605271542054256640)]} 0 0
[junit4] 2> 1560215 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[134 (1605271542055305216)]} 0 0
[junit4] 2> 1560216 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[135 (1605271542057402368)]} 0 0
[junit4] 2> 1560218 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[135 (1605271542058450944)]} 0 0
[junit4] 2> 1560219 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[136 (1605271542060548096)]} 0 0
[junit4] 2> 1560220 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[136 (1605271542061596672)]} 0 0
[junit4] 2> 1560222 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[137 (1605271542062645248)]} 0 0
[junit4] 2> 1560223 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[137 (1605271542064742400)]} 0 0
[junit4] 2> 1560225 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[138 (1605271542066839552)]} 0 0
[junit4] 2> 1560226 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[138 (1605271542067888128)]} 0 0
[junit4] 2> 1560228 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[139 (1605271542068936704)]} 0 0
[junit4] 2> 1560229 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[139 (1605271542069985280)]} 0 0
[junit4] 2> 1560230 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[140 (1605271542072082432)]} 0 0
[junit4] 2> 1560232 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[140 (1605271542073131008)]} 0 0
[junit4] 2> 1560233 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[141 (1605271542074179584)]} 0 0
[junit4] 2> 1560234 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[141 (1605271542076276736)]} 0 0
[junit4] 2> 1560235 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[142 (1605271542077325312)]} 0 0
[junit4] 2> 1560236 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[142 (1605271542078373888)]} 0 0
[junit4] 2> 1560237 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[143 (1605271542079422464)]} 0 0
[junit4] 2> 1560238 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[143 (1605271542080471040)]} 0 0
[junit4] 2> 1560240 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[144 (1605271542081519616)]} 0 0
[junit4] 2> 1560241 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[144 (1605271542082568192)]} 0 0
[junit4] 2> 1560242 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[145 (1605271542084665344)]} 0 0
[junit4] 2> 1560243 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[145 (1605271542085713920)]} 0 0
[junit4] 2> 1560244 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[146 (1605271542086762496)]} 0 0
[junit4] 2> 1560245 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[146 (1605271542087811072)]} 0 0
[junit4] 2> 1560247 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[147 (1605271542088859648)]} 0 0
[junit4] 2> 1560248 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[147 (1605271542089908224)]} 0 0
[junit4] 2> 1560249 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[148 (1605271542090956800)]} 0 0
[junit4] 2> 1560250 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[148 (1605271542093053952)]} 0 0
[junit4] 2> 1560251 INFO (qtp12396988-14965) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[149 (1605271542094102528)]} 0 0
[junit4] 2> 1560253 INFO (qtp32750226-14984) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={wt=javabin&version=2}{add=[149 (1605271542095151104)]} 0 0
[junit4] 2> 1560254 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1605271542097248256,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1560254 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@15408ed commitCommandVersion:1605271542097248256
[junit4] 2> 1560269 INFO (qtp12396988-14964) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6df676[collection1] main]
[junit4] 2> 1560270 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1560271 INFO (searcherExecutor-4736-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6df676[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.5.0):C9) Uninverting(_1(7.5.0):C59)))}
[junit4] 2> 1560272 INFO (qtp12396988-14964) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 18
[junit4] 2> 1560273 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1605271542117171200,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1560273 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1341aaa commitCommandVersion:1605271542117171200
[junit4] 2> 1560283 INFO (qtp32750226-14985) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4209f8[collection1] main]
[junit4] 2> 1560284 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1560285 INFO (searcherExecutor-4750-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4209f8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.5.0):C9) Uninverting(_1(7.5.0):C59)))}
[junit4] 2> 1560286 INFO (qtp32750226-14985) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/jhlig/m path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 13
[junit4] 2> 1560287 ERROR (qtp12396988-14965) [ x:collection1] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: For input string: "non_numeric_value"
[junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:236)
[junit4] 2> at org.apache.solr.search.QParser.getSortSpec(QParser.java:270)
[junit4] 2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:187)
[junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:272)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2541)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:709)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:515)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:377)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:323)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1317)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1219)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:724)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:531)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: java.lang.NumberFormatException: For input string: "non_numeric_value"
[junit4] 2> at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65)
[junit4] 2> at java.lang.Integer.parseInt(Integer.java:580)
[junit4] 2> at java.lang.Integer.valueOf(Integer.java:766)
[junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:233)
[junit4] 2> ... 39 more
[junit4] 2>
[junit4] 2> 1560288 INFO (qtp12396988-14965) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*&distrib=false&start=non_numeric_value&wt=javabin&version=2} status=400 QTime=1
[junit4] 2> 1560289 INFO (qtp12396988-14964) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 1560289 INFO (qtp12396988-14964) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 1560290 INFO (qtp12396988-14965) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 1560290 INFO (qtp12396988-14965) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 1560291 ERROR (qtp12396988-14964) [ x:collection1] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: For input string: "non_numeric_value"
[junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:236)
[junit4] 2> at org.apache.solr.search.QParser.getSortSpec(QParser.java:273)
[junit4] 2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:187)
[junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:272)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2541)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:709)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:515)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:377)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:323)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1317)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1219)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:724)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:531)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: java.lang.NumberFormatException: For input string: "non_numeric_value"
[junit4] 2> at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65)
[junit4] 2> at java.lang.Integer.parseInt(Integer.java:580)
[junit4] 2> at java.lang.Integer.valueOf(Integer.java:766)
[junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:233)
[junit4] 2> ... 39 more
[junit4] 2>
[junit4] 2> 1560292 INFO (qtp12396988-14964) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*&distrib=false&rows=non_numeric_value&wt=javabin&version=2} status=400 QTime=1
[junit4] 2> 1560294 INFO (qtp12396988-14965) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1560301 INFO (qtp32750226-14981) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::213]:33332/jhlig/m|http://127.0.0.1:44037/jhlig/m/collection1&rows=10&version=2&q=*:*&NOW=1530906240599&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1560304 INFO (qtp32750226-14986) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1530906240599&ids=134,2,135,114,126,105,138,128,7,140&isShard=true&shard.url=[ff01::213]:33332/jhlig/m|http://127.0.0.1:44037/jhlig/m/collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1560312 INFO (qtp32750226-14984) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&shards=[ff01::213]:33332/jhlig/m|http://127.0.0.1:44037/jhlig/m/collection1&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=15
[junit4] 2> 1560317 INFO (qtp12396988-14964) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1560323 INFO (qtp32750226-15003) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/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:44037/jhlig/m/collection1&rows=10&version=2&q=*:*&NOW=1530906240623&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1560326 INFO (qtp32750226-14986) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1530906240623&ids=132,12,146,124,102,17,107,129,8,142&isShard=true&shard.url=http://127.0.0.1:44037/jhlig/m/collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1560338 INFO (qtp32750226-14985) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&shards=http://127.0.0.1:44037/jhlig/m/collection1&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=17
[junit4] 2> 1560340 INFO (qtp12396988-14965) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1560353 INFO (qtp32750226-15003) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:44037/jhlig/m/collection1|[ff01::114]:33332/jhlig/m|[ff01::213]:33332/jhlig/m|[ff01::083]:33332/jhlig/m&rows=10&version=2&q=*:*&NOW=1530906240651&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1560356 INFO (qtp32750226-14986) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1530906240651&ids=1,133,13,15,125,6,106,117,1001,120&isShard=true&shard.url=http://127.0.0.1:44037/jhlig/m/collection1|[ff01::114]:33332/jhlig/m|[ff01::213]:33332/jhlig/m|[ff01::083]:33332/jhlig/m&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1560361 INFO (qtp32750226-14984) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&shards=http://127.0.0.1:44037/jhlig/m/collection1|[ff01::114]:33332/jhlig/m|[ff01::213]:33332/jhlig/m|[ff01::083]:33332/jhlig/m&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=12
[junit4] 2> 1560363 INFO (qtp12396988-14964) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1560366 INFO (qtp32750226-15003) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/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:44037/jhlig/m/collection1&rows=10&version=2&q=*:*&NOW=1530906240667&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1560369 INFO (qtp32750226-15003) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1530906240667&ids=100,147,137,126,5,16,107,8,141,10&isShard=true&shard.url=http://127.0.0.1:44037/jhlig/m/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1560372 INFO (qtp32750226-14985) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&shards=http://127.0.0.1:44037/jhlig/m/collection1&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 1560374 INFO (qtp12396988-14965) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1560378 INFO (qtp32750226-14986) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:44037/jhlig/m/collection1|[ff01::083]:33332/jhlig/m|[ff01::114]:33332/jhlig/m|[ff01::213]:33332/jhlig/m&rows=10&version=2&q=*:*&NOW=1530906240678&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1560381 INFO (qtp32750226-15003) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1530906240678&ids=2,135,136,104,6,139,7,107,129,9&isShard=true&shard.url=http://127.0.0.1:44037/jhlig/m/collection1|[ff01::083]:33332/jhlig/m|[ff01::114]:33332/jhlig/m|[ff01::213]:33332/jhlig/m&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1560385 INFO (qtp32750226-14984) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&shards=http://127.0.0.1:44037/jhlig/m/collection1|[ff01::083]:33332/jhlig/m|[ff01::114]:33332/jhlig/m|[ff01::213]:33332/jhlig/m&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=9
[junit4] 2> 1560388 INFO (qtp12396988-14964) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1560391 INFO (qtp32750226-14986) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=http://127.0.0.1:44037/jhlig/m/collection1&rows=10&version=2&q=*:*&NOW=1530906240692&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1560393 INFO (qtp32750226-15003) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1530906240692&ids=11,12,146,124,114,137,116,8,140,131&isShard=true&shard.url=http://127.0.0.1:44037/jhlig/m/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1560396 INFO (qtp32750226-14985) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&shards=http://127.0.0.1:44037/jhlig/m/collection1&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 1560398 INFO (qtp12396988-14965) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1560407 INFO (qtp32750226-14986) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=[ff01::083]:33332/jhlig/m|[ff01::213]:33332/jhlig/m|[ff01::114]:33332/jhlig/m|http://127.0.0.1:44037/jhlig/m/collection1&rows=10&version=2&q=*:*&NOW=1530906240702&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1560409 INFO (qtp32750226-15003) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1530906240702&ids=11,110,13,101,146,16,17,149,106,140&isShard=true&shard.url=[ff01::083]:33332/jhlig/m|[ff01::213]:33332/jhlig/m|[ff01::114]:33332/jhlig/m|http://127.0.0.1:44037/jhlig/m/collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1560412 INFO (qtp32750226-14984) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&shards=[ff01::083]:33332/jhlig/m|[ff01::213]:33332/jhlig/m|[ff01::114]:33332/jhlig/m|http://127.0.0.1:44037/jhlig/m/collection1&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=12
[junit4] 2> 1560415 INFO (qtp12396988-14964) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1560418 INFO (qtp32750226-14986) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=[ff01::213]:33332/jhlig/m|http://127.0.0.1:44037/jhlig/m/collection1|[ff01::083]:33332/jhlig/m|[ff01::114]:33332/jhlig/m&rows=10&version=2&q=*:*&NOW=1530906240719&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1560420 INFO (qtp32750226-15003) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1530906240719&ids=143,113,135,125,105,7,128,119,108,131&isShard=true&shard.url=[ff01::213]:33332/jhlig/m|http://127.0.0.1:44037/jhlig/m/collection1|[ff01::083]:33332/jhlig/m|[ff01::114]:33332/jhlig/m&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1560422 INFO (qtp32750226-14985) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&shards=[ff01::213]:33332/jhlig/m|http://127.0.0.1:44037/jhlig/m/collection1|[ff01::083]:33332/jhlig/m|[ff01::114]:33332/jhlig/m&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 1560423 INFO (qtp12396988-14965) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1560426 INFO (qtp32750226-14986) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=[ff01::114]:33332/jhlig/m|[ff01::213]:33332/jhlig/m|[ff01::083]:33332/jhlig/m|http://127.0.0.1:44037/jhlig/m/collection1&rows=10&version=2&q=*:*&NOW=1530906240727&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1560428 INFO (qtp32750226-15003) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1530906240727&ids=12,135,14,114,103,4,5,137,128,119&isShard=true&shard.url=[ff01::114]:33332/jhlig/m|[ff01::213]:33332/jhlig/m|[ff01::083]:33332/jhlig/m|http://127.0.0.1:44037/jhlig/m/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1560430 INFO (qtp32750226-14984) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&shards=[ff01::114]:33332/jhlig/m|[ff01::213]:33332/jhlig/m|[ff01::083]:33332/jhlig/m|http://127.0.0.1:44037/jhlig/m/collection1&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 1560431 INFO (qtp12396988-14964) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1560435 INFO (qtp32750226-14986) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/jhlig/m path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::083]:33332/jhlig/m|http://127.0.0.1:44037/jhlig/m/collection1&rows=10&version=2&q=*:*&NOW=1530906240735&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1560437 INFO (qtp32750226-15003) [ x:collection1] o.a.s.c.S.Re
[...truncated too long message...]
Name = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@6b1e0
[junit4] 2> 1614191 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@527513{/jhlig/m,null,UNAVAILABLE}
[junit4] 2> 1614191 INFO (TEST-TestDistributedSearch.test-seed#[CE529A2E7A9C3BDA]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=CE529A2E7A9C3BDA -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-LB -Dtests.timezone=America/Argentina/ComodRivadavia -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 58.9s J0 | TestDistributedSearch.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Expected to find shardAddress in the up shard info: {error=org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded,trace=org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:460)
[junit4] > at org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java:279)
[junit4] > at org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:175)
[junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] > at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > ,time=12}
[junit4] > at __randomizedtesting.SeedInfo.seed([CE529A2E7A9C3BDA:4606A5F4D4605622]:0)
[junit4] > at org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1192)
[junit4] > at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1133)
[junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:993)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1034)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:983)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_CE529A2E7A9C3BDA-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {severity=PostingsFormat(name=Asserting), foo_sev_enum=Lucene50(blocksize=128), SubjectTerms_mfacet=PostingsFormat(name=Memory), multiDefault=PostingsFormat(name=MockRandom), rnd_b=Lucene50(blocksize=128), a_s=PostingsFormat(name=Asserting), oddField_s=PostingsFormat(name=Asserting), lowerfilt1and2=PostingsFormat(name=MockRandom), a_t=PostingsFormat(name=Memory), foo_b=PostingsFormat(name=Asserting), lowerfilt=PostingsFormat(name=MockRandom), id=Lucene50(blocksize=128), text=PostingsFormat(name=Asserting)}, docValues:{other_tl1=DocValuesFormat(name=Asserting), range_facet_l_dv=DocValuesFormat(name=Lucene70), n_l1=DocValuesFormat(name=Asserting), a_n_tdt=DocValuesFormat(name=Lucene70), intDefault=DocValuesFormat(name=Asserting), n_td1=DocValuesFormat(name=Lucene70), n_d1=DocValuesFormat(name=Asserting), range_facet_l=DocValuesFormat(name=Asserting), n_f1=DocValuesFormat(name=Lucene70), n_tl1=DocValuesFormat(name=Lucene70), n_tf1=DocValuesFormat(name=Asserting), intDvoDefault=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Asserting), severity=DocValuesFormat(name=Memory), foo_sev_enum=DocValuesFormat(name=Lucene70), n_dt1=DocValuesFormat(name=Lucene70), a_i1=DocValuesFormat(name=Asserting), b_n_tdt=DocValuesFormat(name=Asserting), n_ti1=DocValuesFormat(name=Lucene70), _version_=DocValuesFormat(name=Asserting), n_tdt1=DocValuesFormat(name=Asserting), id_i1=DocValuesFormat(name=Lucene70), foo_d=DocValuesFormat(name=Lucene70), range_facet_i_dv=DocValuesFormat(name=Asserting), foo_f=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=1286, maxMBSortInHeap=7.682103169855205, sim=RandomSimilarity(queryNorm=true): {}, locale=ar-LB, timezone=America/Argentina/ComodRivadavia
[junit4] 2> NOTE: Linux 4.15.0-24-generic i386/Oracle Corporation 1.8.0_172 (32-bit)/cpus=8,threads=1,free=126186864,total=323174400
[junit4] 2> NOTE: All tests run in this JVM: [TestCopyFieldCollectionResource, TestSolrQueryResponse, JsonLoaderTest, TestSolrCloudWithDelegationTokens, HdfsRecoverLeaseTest, CloudMLTQParserTest, CircularListTest, ConcurrentCreateRoutedAliasTest, TestRuleBasedAuthorizationPlugin, TestCrossCoreJoin, EnumFieldTest, SimpleCollectionCreateDeleteTest, TestLuceneMatchVersion, HdfsUnloadDistributedZkTest, TestRequestForwarding, TestGroupingSearch, TestApiFramework, TestPseudoReturnFields, CustomCollectionTest, ImplicitSnitchTest, ZkNodePropsTest, DeleteShardTest, TestBackupRepositoryFactory, TestObjectReleaseTracker, SolrCloudExampleTest, BasicDistributedZk2Test, TestAuthenticationFramework, IndexBasedSpellCheckerTest, TestTlogReplica, TestSurroundQueryParser, SoftAutoCommitTest, SortSpecParsingTest, TestStandardQParsers, TriggerCooldownIntegrationTest, TestMultiValuedNumericRangeQuery, TestPrepRecovery, SaslZkACLProviderTest, GraphQueryTest, V2StandaloneTest, TokenizerChainTest, PreAnalyzedUpdateProcessorTest, WordBreakSolrSpellCheckerTest, UUIDUpdateProcessorFallbackTest, HdfsAutoAddReplicasIntegrationTest, TestRequestStatusCollectionAPI, TestBinaryResponseWriter, TestReloadAndDeleteDocs, TestOverriddenPrefixQueryForCustomFieldType, TemplateUpdateProcessorTest, SynonymTokenizerTest, DistributedFacetPivotSmallAdvancedTest, TestCloudInspectUtil, TestJavabinTupleStreamParser, MetricsConfigTest, MetricsHistoryIntegrationTest, TestFieldCacheReopen, SolrGraphiteReporterTest, TestClusterStateMutator, TestAnalyzedSuggestions, TestBlobHandler, DefaultValueUpdateProcessorTest, TestFieldTypeResource, TestNonDefinedSimilarityFactory, TestClusterStateProvider, TestEmbeddedSolrServerConstructors, StatsComponentTest, SchemaVersionSpecificBehaviorTest, TestFieldCollectionResource, PolyFieldTest, TestComponentsName, TestUtils, OverseerModifyCollectionTest, TestUniqueKeyFieldResource, TestManagedResourceStorage, TestNRTOpen, TestLRUStatsCache, PrimitiveFieldTypeTest, SparseHLLTest, RangeFacetCloudTest, TestManagedSynonymGraphFilterFactory, TestLRUCache, TestCollationFieldDocValues, DistanceUnitsTest, TestPhraseSuggestions, TestDirectoryFactory, CheckHdfsIndexTest, TestSolrJ, FastVectorHighlighterTest, TestManagedSchemaAPI, TestNodeAddedTrigger, TestNumericTerms32, MaxSizeAutoCommitTest, BigEndianAscendingWordSerializerTest, LIROnShardRestartTest, DeleteLastCustomShardedReplicaTest, TestSimpleQParserPlugin, TestSweetSpotSimilarityFactory, TestSlowCompositeReaderWrapper, TestShortCircuitedRequests, NotRequiredUniqueKeyTest, LeaderElectionIntegrationTest, TestDocTermOrds, MoveReplicaHDFSFailoverTest, TestLeaderElectionWithEmptyReplica, TestJmxIntegration, SignatureUpdateProcessorFactoryTest, TestSubQueryTransformerCrossCore, CollectionPropsTest, TaggerTest, LeaderFailureAfterFreshStartTest, ReplaceNodeNoTargetTest, TestStressReorder, CSVRequestHandlerTest, TestFreeTextSuggestions, AlternateDirectoryTest, CreateCollectionCleanupTest, TriLevelCompositeIdRoutingTest, TestSolrConfigHandlerConcurrent, TestConfigSetProperties, BufferStoreTest, CachingDirectoryFactoryTest, TestSolrCoreProperties, TestSQLHandler, TestStressCloudBlindAtomicUpdates, TestPointFields, TestLockTree, OpenCloseCoreStressTest, AutoScalingHandlerTest, TestFieldSortValues, CursorPagingTest, SearchHandlerTest, RequestLoggingTest, TestMacros, TestFieldCacheVsDocValues, EchoParamsTest, MinimalSchemaTest, OutputWriterTest, SampleTest, SolrInfoBeanTest, TestDistributedSearch]
[junit4] Completed [417/821 (1!)] on J0 in 58.96s, 1 test, 1 failure <<< FAILURES!
[...truncated 47903 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/2273/consoleText
[repro] Revision: 9a6706ed32646e74fb64a8b2caa05fd6bc7e8a35
[repro] Ant options: "-Dargs=-client -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=CE529A2E7A9C3BDA -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-LB -Dtests.timezone=America/Argentina/ComodRivadavia -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestDistributedSearch
[repro] ant compile-test
[...truncated 3318 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestDistributedSearch" -Dtests.showOutput=onerror "-Dargs=-client -XX:+UseSerialGC" -Dtests.seed=CE529A2E7A9C3BDA -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-LB -Dtests.timezone=America/Argentina/ComodRivadavia -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 75 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.TestDistributedSearch
[repro] Exiting with code 0
[...truncated 40 lines...]