You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/03/21 08:20:05 UTC
[JENKINS] Lucene-Solr-7.x-Linux (64bit/jdk-9.0.4) - Build # 1568 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1568/
Java: 64bit/jdk-9.0.4 -XX:-UseCompressedOops -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:273) at org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:175) at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514) at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) at java.base/java.lang.Thread.run(Thread.java:844) ,time=2}
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:273)
at org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:175)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
at java.base/java.lang.Thread.run(Thread.java:844)
,time=2}
at __randomizedtesting.SeedInfo.seed([BA3E1CFA45D25DB9:326A2320EB2E3041]: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:1191)
at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1132)
at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:992)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1019)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 1786 lines...]
[junit4] JVM J0: stdout was not empty, see: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/lucene/build/core/test/temp/junit4-J0-20180321_070555_753188823253364912339.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] codec: CheapBastard, pf: LuceneFixedGap, dvf: Lucene70
[junit4] <<< JVM J0: EOF ----
[...truncated 11431 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_BA3E1CFA45D25DB9-001/init-core-data-001
[junit4] 2> 985690 INFO (SUITE-TestDistributedSearch-seed#[BA3E1CFA45D25DB9]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 985691 INFO (SUITE-TestDistributedSearch-seed#[BA3E1CFA45D25DB9]-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> 985691 INFO (SUITE-TestDistributedSearch-seed#[BA3E1CFA45D25DB9]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 985691 INFO (SUITE-TestDistributedSearch-seed#[BA3E1CFA45D25DB9]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 986170 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_BA3E1CFA45D25DB9-001/tempDir-001/control/cores/collection1
[junit4] 2> 986172 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 986172 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 986172 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 986172 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 986173 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6a12d613{/,null,AVAILABLE}
[junit4] 2> 986173 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1a45c1b0{HTTP/1.1,[http/1.1]}{127.0.0.1:44483}
[junit4] 2> 986173 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.e.j.s.Server Started @988026ms
[junit4] 2> 986173 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=44483, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_BA3E1CFA45D25DB9-001/tempDir-001/control/cores}
[junit4] 2> 986173 ERROR (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 986173 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.4.0
[junit4] 2> 986173 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 986173 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 986174 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-21T07:43:12.764022Z
[junit4] 2> 986174 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_BA3E1CFA45D25DB9-001/tempDir-001/control/solr.xml
[junit4] 2> 986175 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 986175 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 986176 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@14a96803, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 986200 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14a96803
[junit4] 2> 986210 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14a96803
[junit4] 2> 986210 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14a96803
[junit4] 2> 986212 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_BA3E1CFA45D25DB9-001/tempDir-001/control/cores
[junit4] 2> 986212 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 986212 INFO (coreLoadExecutor-4060-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 986213 INFO (coreLoadExecutor-4060-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> 986235 INFO (coreLoadExecutor-4060-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
[junit4] 2> 986257 INFO (coreLoadExecutor-4060-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 986371 INFO (coreLoadExecutor-4060-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 986379 INFO (coreLoadExecutor-4060-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> 986379 INFO (coreLoadExecutor-4060-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@14a96803
[junit4] 2> 986379 INFO (coreLoadExecutor-4060-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 986379 INFO (coreLoadExecutor-4060-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/J2/temp/solr.TestDistributedSearch_BA3E1CFA45D25DB9-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 986381 INFO (coreLoadExecutor-4060-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=18, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 986426 INFO (coreLoadExecutor-4060-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 986426 INFO (coreLoadExecutor-4060-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 986427 INFO (coreLoadExecutor-4060-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 986427 INFO (coreLoadExecutor-4060-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 986428 INFO (coreLoadExecutor-4060-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=99.8359375, floorSegmentMB=1.337890625, forceMergeDeletesPctAllowed=22.16846377581288, segmentsPerTier=17.0, maxCFSSegmentSizeMB=0.43359375, noCFSRatio=0.12469113241363222
[junit4] 2> 986428 INFO (coreLoadExecutor-4060-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@30b5388c[collection1] main]
[junit4] 2> 986429 INFO (coreLoadExecutor-4060-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 986448 INFO (coreLoadExecutor-4060-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 986540 INFO (coreLoadExecutor-4060-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 986540 INFO (searcherExecutor-4061-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 986540 INFO (coreLoadExecutor-4060-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1595532318479482880
[junit4] 2> 986540 INFO (searcherExecutor-4061-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 986540 INFO (searcherExecutor-4061-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 986540 INFO (searcherExecutor-4061-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 986541 INFO (searcherExecutor-4061-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 986541 INFO (searcherExecutor-4061-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 986541 INFO (searcherExecutor-4061-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 986541 INFO (searcherExecutor-4061-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 986541 INFO (searcherExecutor-4061-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 986542 INFO (searcherExecutor-4061-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@30b5388c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 986629 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_BA3E1CFA45D25DB9-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 986630 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 986631 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 986631 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 986631 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 986631 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@34de9412{/,null,AVAILABLE}
[junit4] 2> 986631 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@207b7b17{HTTP/1.1,[http/1.1]}{127.0.0.1:38477}
[junit4] 2> 986631 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.e.j.s.Server Started @988484ms
[junit4] 2> 986631 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=38477, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_BA3E1CFA45D25DB9-001/tempDir-001/shard0/cores}
[junit4] 2> 986631 ERROR (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 986631 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.4.0
[junit4] 2> 986631 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 986631 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 986632 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-21T07:43:13.222020Z
[junit4] 2> 986632 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_BA3E1CFA45D25DB9-001/tempDir-001/shard0/solr.xml
[junit4] 2> 986633 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 986633 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 986634 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@14a96803, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 986668 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14a96803
[junit4] 2> 986675 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14a96803
[junit4] 2> 986675 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14a96803
[junit4] 2> 986676 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_BA3E1CFA45D25DB9-001/tempDir-001/shard0/cores
[junit4] 2> 986676 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 986677 INFO (coreLoadExecutor-4071-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 986677 INFO (coreLoadExecutor-4071-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> 986692 INFO (coreLoadExecutor-4071-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
[junit4] 2> 986705 INFO (coreLoadExecutor-4071-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 986796 INFO (coreLoadExecutor-4071-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 986801 INFO (coreLoadExecutor-4071-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> 986801 INFO (coreLoadExecutor-4071-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@14a96803
[junit4] 2> 986801 INFO (coreLoadExecutor-4071-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 986801 INFO (coreLoadExecutor-4071-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/J2/temp/solr.TestDistributedSearch_BA3E1CFA45D25DB9-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 986802 INFO (coreLoadExecutor-4071-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=18, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 986832 INFO (coreLoadExecutor-4071-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 986832 INFO (coreLoadExecutor-4071-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 986833 INFO (coreLoadExecutor-4071-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 986833 INFO (coreLoadExecutor-4071-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 986833 INFO (coreLoadExecutor-4071-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=99.8359375, floorSegmentMB=1.337890625, forceMergeDeletesPctAllowed=22.16846377581288, segmentsPerTier=17.0, maxCFSSegmentSizeMB=0.43359375, noCFSRatio=0.12469113241363222
[junit4] 2> 986834 INFO (coreLoadExecutor-4071-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@17cc46e8[collection1] main]
[junit4] 2> 986834 INFO (coreLoadExecutor-4071-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 986852 INFO (coreLoadExecutor-4071-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 986944 INFO (coreLoadExecutor-4071-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 986944 INFO (searcherExecutor-4072-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 986944 INFO (coreLoadExecutor-4071-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1595532318903107584
[junit4] 2> 986944 INFO (searcherExecutor-4072-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 986944 INFO (searcherExecutor-4072-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 986944 INFO (searcherExecutor-4072-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 986944 INFO (searcherExecutor-4072-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 986945 INFO (searcherExecutor-4072-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 986945 INFO (searcherExecutor-4072-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 986945 INFO (searcherExecutor-4072-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 986945 INFO (searcherExecutor-4072-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 986946 INFO (searcherExecutor-4072-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@17cc46e8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 986946 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 986948 INFO (qtp1695081451-12069) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1595532318906253312)} 0 0
[junit4] 2> 986949 INFO (qtp1780084836-12086) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1595532318908350464)} 0 0
[junit4] 2> 986952 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1595532318909399040)]} 0 1
[junit4] 2> 986953 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1595532318911496192)]} 0 0
[junit4] 2> 986954 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1595532318912544768)]} 0 0
[junit4] 2> 986954 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1595532318913593344)]} 0 0
[junit4] 2> 986955 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1595532318914641920)]} 0 0
[junit4] 2> 986956 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1595532318914641920)]} 0 0
[junit4] 2> 986956 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1595532318915690496)]} 0 0
[junit4] 2> 986957 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1595532318916739072)]} 0 0
[junit4] 2> 986957 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1595532318916739072)]} 0 0
[junit4] 2> 986958 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1595532318917787648)]} 0 0
[junit4] 2> 986959 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1595532318918836224)]} 0 0
[junit4] 2> 986959 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1595532318918836224)]} 0 0
[junit4] 2> 986960 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1595532318919884800)]} 0 0
[junit4] 2> 986962 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1595532318921981952)]} 0 0
[junit4] 2> 986963 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1595532318923030528)]} 0 0
[junit4] 2> 986964 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1595532318924079104)]} 0 0
[junit4] 2> 986965 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1595532318925127680)]} 0 0
[junit4] 2> 986965 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1595532318925127680)]} 0 0
[junit4] 2> 986966 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1595532318926176256,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 986966 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@273f3ac4 commitCommandVersion:1595532318926176256
[junit4] 2> 986973 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@59958333[collection1] main]
[junit4] 2> 986974 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 986975 INFO (searcherExecutor-4061-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@59958333[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.4.0):c9)))}
[junit4] 2> 986975 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 9
[junit4] 2> 986976 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1595532318936662016,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 986976 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54dea6d8 commitCommandVersion:1595532318936662016
[junit4] 2> 987004 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@714810f4[collection1] main]
[junit4] 2> 987005 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 987006 INFO (searcherExecutor-4072-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@714810f4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.4.0):c9)))}
[junit4] 2> 987006 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 30
[junit4] 2> 987008 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1595532318969167872)]} 0 1
[junit4] 2> 987010 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1595532318971265024)]} 0 1
[junit4] 2> 987011 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1595532318972313600)]} 0 0
[junit4] 2> 987012 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1595532318974410752)]} 0 0
[junit4] 2> 987013 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1595532318975459328)]} 0 0
[junit4] 2> 987014 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1595532318976507904)]} 0 0
[junit4] 2> 987015 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1595532318976507904)]} 0 0
[junit4] 2> 987022 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1595532318977556480)]} 0 7
[junit4] 2> 987024 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1595532318985945088)]} 0 1
[junit4] 2> 987025 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1595532318986993664)]} 0 0
[junit4] 2> 987026 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1595532318989090816)]} 0 0
[junit4] 2> 987027 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1595532318989090816)]} 0 0
[junit4] 2> 987027 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1595532318990139392)]} 0 0
[junit4] 2> 987028 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1595532318991187968)]} 0 0
[junit4] 2> 987029 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1595532318992236544)]} 0 0
[junit4] 2> 987030 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1595532318992236544)]} 0 0
[junit4] 2> 987030 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1595532318993285120)]} 0 0
[junit4] 2> 987031 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1595532318994333696)]} 0 0
[junit4] 2> 987032 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1595532318995382272)]} 0 0
[junit4] 2> 987032 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1595532318995382272)]} 0 0
[junit4] 2> 987033 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1595532318996430848)]} 0 0
[junit4] 2> 987034 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1595532318996430848)]} 0 0
[junit4] 2> 987034 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1595532318997479424)]} 0 0
[junit4] 2> 987035 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1595532318998528000)]} 0 0
[junit4] 2> 987035 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1595532318998528000)]} 0 0
[junit4] 2> 987036 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1595532318999576576)]} 0 0
[junit4] 2> 987036 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1595532318999576576)]} 0 0
[junit4] 2> 987037 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1595532319000625152)]} 0 0
[junit4] 2> 987037 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1595532319000625152)]} 0 0
[junit4] 2> 987038 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1595532319001673728)]} 0 0
[junit4] 2> 987038 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1595532319001673728)]} 0 0
[junit4] 2> 987039 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1595532319002722304)]} 0 0
[junit4] 2> 987039 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1595532319002722304)]} 0 0
[junit4] 2> 987040 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1595532319003770880)]} 0 0
[junit4] 2> 987041 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1595532319003770880)]} 0 0
[junit4] 2> 987041 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1595532319004819456)]} 0 0
[junit4] 2> 987042 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1595532319005868032)]} 0 0
[junit4] 2> 987043 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1595532319005868032)]} 0 0
[junit4] 2> 987043 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1595532319006916608)]} 0 0
[junit4] 2> 987044 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1595532319007965184)]} 0 0
[junit4] 2> 987044 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1595532319007965184)]} 0 0
[junit4] 2> 987045 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1595532319009013760)]} 0 0
[junit4] 2> 987045 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1595532319009013760)]} 0 0
[junit4] 2> 987046 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1595532319010062336)]} 0 0
[junit4] 2> 987047 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1595532319010062336)]} 0 0
[junit4] 2> 987047 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1595532319011110912)]} 0 0
[junit4] 2> 987048 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1595532319012159488)]} 0 0
[junit4] 2> 987048 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1595532319012159488)]} 0 0
[junit4] 2> 987049 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1595532319012159489)]} 0 0
[junit4] 2> 987049 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1595532319013208064)]} 0 0
[junit4] 2> 987050 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1595532319014256640)]} 0 0
[junit4] 2> 987051 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1595532319015305216)]} 0 0
[junit4] 2> 987052 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1595532319016353792)]} 0 0
[junit4] 2> 987053 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1595532319017402368)]} 0 0
[junit4] 2> 987053 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1595532319017402368)]} 0 0
[junit4] 2> 987054 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1595532319018450944)]} 0 0
[junit4] 2> 987055 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1595532319018450944)]} 0 0
[junit4] 2> 987055 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1595532319019499520)]} 0 0
[junit4] 2> 987056 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1595532319020548096)]} 0 0
[junit4] 2> 987056 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1595532319020548096)]} 0 0
[junit4] 2> 987057 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1595532319021596672)]} 0 0
[junit4] 2> 987058 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1595532319021596672)]} 0 0
[junit4] 2> 987058 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1595532319022645248)]} 0 0
[junit4] 2> 987059 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1595532319023693824)]} 0 0
[junit4] 2> 987059 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1595532319023693824)]} 0 0
[junit4] 2> 987060 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1595532319024742400)]} 0 0
[junit4] 2> 987061 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1595532319025790976)]} 0 0
[junit4] 2> 987062 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1595532319026839552)]} 0 0
[junit4] 2> 987063 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1595532319027888128)]} 0 0
[junit4] 2> 987064 INFO (qtp1780084836-12091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1595532319027888128)]} 0 0
[junit4] 2> 987065 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1595532319028936704)]} 0 0
[junit4] 2> 987066 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1595532319029985280)]} 0 0
[junit4] 2> 987067 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1595532319031033856)]} 0 0
[junit4] 2> 987068 INFO (qtp1780084836-12091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1595532319032082432)]} 0 0
[junit4] 2> 987068 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1595532319033131008)]} 0 0
[junit4] 2> 987069 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1595532319034179584)]} 0 0
[junit4] 2> 987070 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1595532319035228160)]} 0 0
[junit4] 2> 987071 INFO (qtp1780084836-12091) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1595532319036276736)]} 0 0
[junit4] 2> 987072 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1595532319037325312)]} 0 0
[junit4] 2> 987073 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1595532319037325312)]} 0 0
[junit4] 2> 987074 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1595532319038373888)]} 0 0
[junit4] 2> 987075 INFO (qtp1780084836-12086) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1595532319039422464)]} 0 0
[junit4] 2> 987076 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1595532319040471040)]} 0 0
[junit4] 2> 987080 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1595532319045713920)]} 0 0
[junit4] 2> 987081 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1595532319046762496)]} 0 0
[junit4] 2> 987082 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1595532319046762496)]} 0 0
[junit4] 2> 987082 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1595532319047811072)]} 0 0
[junit4] 2> 987083 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1595532319048859648)]} 0 0
[junit4] 2> 987084 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1595532319049908224)]} 0 0
[junit4] 2> 987088 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1595532319054102528)]} 0 0
[junit4] 2> 987089 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1595532319054102528)]} 0 0
[junit4] 2> 987089 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1595532319055151104)]} 0 0
[junit4] 2> 987090 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1595532319056199680)]} 0 0
[junit4] 2> 987091 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1595532319057248256)]} 0 0
[junit4] 2> 987092 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1595532319058296832)]} 0 0
[junit4] 2> 987093 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1595532319059345408)]} 0 0
[junit4] 2> 987094 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1595532319060393984)]} 0 0
[junit4] 2> 987095 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1595532319060393984)]} 0 0
[junit4] 2> 987096 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1595532319061442560)]} 0 0
[junit4] 2> 987107 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1595532319072976896)]} 0 0
[junit4] 2> 987108 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1595532319075074048)]} 0 0
[junit4] 2> 987109 INFO (qtp1780084836-12105) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1595532319075074048)]} 0 0
[junit4] 2> 987109 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1595532319076122624)]} 0 0
[junit4] 2> 987110 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1595532319077171200)]} 0 0
[junit4] 2> 987111 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1595532319077171200)]} 0 0
[junit4] 2> 987111 INFO (qtp1780084836-12105) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1595532319078219776)]} 0 0
[junit4] 2> 987112 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1595532319079268352)]} 0 0
[junit4] 2> 987113 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1595532319079268352)]} 0 0
[junit4] 2> 987113 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1595532319080316928)]} 0 0
[junit4] 2> 987114 INFO (qtp1780084836-12105) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1595532319081365504)]} 0 0
[junit4] 2> 987115 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1595532319081365504)]} 0 0
[junit4] 2> 987115 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1595532319082414080)]} 0 0
[junit4] 2> 987116 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1595532319083462656)]} 0 0
[junit4] 2> 987117 INFO (qtp1780084836-12105) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1595532319083462656)]} 0 0
[junit4] 2> 987117 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1595532319084511232)]} 0 0
[junit4] 2> 987118 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1595532319085559808)]} 0 0
[junit4] 2> 987119 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1595532319086608384)]} 0 0
[junit4] 2> 987119 INFO (qtp1780084836-12105) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1595532319086608384)]} 0 0
[junit4] 2> 987120 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1595532319087656960,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 987120 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@273f3ac4 commitCommandVersion:1595532319087656960
[junit4] 2> 987136 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2a4b07f3[collection1] main]
[junit4] 2> 987138 INFO (searcherExecutor-4061-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2a4b07f3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.4.0):c9) Uninverting(_1(7.4.0):c59)))}
[junit4] 2> 987139 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 987139 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 18
[junit4] 2> 987139 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1595532319107579904,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 987140 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54dea6d8 commitCommandVersion:1595532319107579904
[junit4] 2> 987152 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3d2c5f71[collection1] main]
[junit4] 2> 987154 INFO (searcherExecutor-4072-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3d2c5f71[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.4.0):c9) Uninverting(_1(7.4.0):c59)))}
[junit4] 2> 987154 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 987154 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 15
[junit4] 2> 987155 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 987155 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 987156 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 987156 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 987157 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987165 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:38477//collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1521618193749&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987168 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193749&ids=110,2,101,146,15,5,6,139,118,131&isShard=true&shard.url=http://127.0.0.1:38477//collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 987173 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:38477//collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=13
[junit4] 2> 987174 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987176 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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:38477//collection1&rows=10&version=2&q=*:*&NOW=1521618193765&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987178 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193765&ids=121,133,111,147,125,148,106,8,107,140&isShard=true&shard.url=http://127.0.0.1:38477//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 987179 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:38477//collection1&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 987180 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987195 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:38477//collection1&rows=10&version=2&q=*:*&NOW=1521618193772&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987196 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193772&ids=133,13,135,136,126,116,118,108,119,109&isShard=true&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:38477//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 987198 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:38477//collection1&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=16
[junit4] 2> 987199 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987202 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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:38477//collection1|[ff01::213]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1521618193790&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987203 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193790&ids=123,134,4,5,137,117,7,1001,141,131&isShard=true&shard.url=http://127.0.0.1:38477//collection1|[ff01::213]:33332/|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 987204 INFO (qtp1780084836-12105) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:38477//collection1|[ff01::213]:33332/|[ff01::083]:33332/&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 987205 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987209 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:38477//collection1&rows=10&version=2&q=*:*&NOW=1521618193797&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987210 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193797&ids=143,122,124,147,125,105,127,118,141,130&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:38477//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 987211 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:38477//collection1&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 987212 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987214 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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:38477//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1521618193803&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987216 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193803&ids=100,144,123,103,126,104,115,138,6,119&isShard=true&shard.url=http://127.0.0.1:38477//collection1|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 987217 INFO (qtp1780084836-12105) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:38477//collection1|[ff01::114]:33332/&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 987218 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987220 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:38477//collection1&rows=10&version=2&q=*:*&NOW=1521618193810&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987222 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193810&ids=132,143,111,100,147,103,115,17,139,8&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:38477//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 987223 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:38477//collection1&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 987224 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987226 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:38477//collection1&rows=10&version=2&q=*:*&NOW=1521618193815&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987227 INFO (qtp1780084836-12104) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193815&ids=144,12,122,116,6,7,106,107,129,109&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:38477//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 987228 INFO (qtp1780084836-12105) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:38477//collection1&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 987230 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987232 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=http://127.0.0.1:38477//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1521618193821&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987233 INFO (qtp1780084836-12104) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193821&ids=143,122,124,125,4,147,104,128,139,10&isShard=true&shard.url=http://127.0.0.1:38477//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 987234 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:38477//collection1|[ff01::213]:33332/&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 987235 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987237 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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/|[ff01::213]:33332/|http://127.0.0.1:38477//collection1&rows=10&version=2&q=*:*&NOW=1521618193826&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987238 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193826&ids=100,12,2,3,114,115,6,105,138,7&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:38477//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 987239 INFO (qtp1780084836-12105) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:38477//collection1&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 987240 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987242 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=[ff01::213]:33332/|http://127.0.0.1:38477//collection1|[ff01::114]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1521618193831&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987243 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193831&ids=132,136,5,138,1001,139,8,108,140,142&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:38477//collection1|[ff01::114]:33332/|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 987244 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:38477//collection1|[ff01::114]:33332/|[ff01::083]:33332/&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 987244 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987246 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:38477//collection1|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1521618193836&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987247 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193836&ids=11,146,15,104,115,6,127,116,7,128&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:38477//collection1|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 987248 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:38477//collection1|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 987249 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987252 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:38477//collection1&rows=10&version=2&q=*:*&NOW=1521618193840&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987254 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193840&ids=110,1,145,123,137,6,106,7,118,141&isShard=true&shard.url=http://127.0.0.1:38477//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 987255 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:38477//collection1&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 987256 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987260 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=[ff01::213]:33332/|http://127.0.0.1:38477//collection1&rows=10&version=2&q=*:*&NOW=1521618193849&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987261 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193849&ids=121,133,100,144,12,103,104,148,139,120&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:38477//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 987262 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:38477//collection1&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 987263 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987267 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:38477//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1521618193856&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987268 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193856&ids=122,2,147,136,148,105,107,8,109,120&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:38477//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 987269 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:38477//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 987270 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987272 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:38477//collection1&rows=10&version=2&q=*:*&NOW=1521618193861&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987273 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193861&ids=11,13,135,14,3,124,114,6,117,9&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:38477//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 987275 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:38477//collection1&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 987276 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987278 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:38477//collection1&rows=10&version=2&q=*:*&NOW=1521618193868&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987280 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193868&ids=1,145,134,135,115,128,107,118,9,140&isShard=true&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:38477//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 987281 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:38477//collection1&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 987282 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987285 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+asc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:38477//collection1&rows=10&version=2&q=*:*&NOW=1521618193874&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987286 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193874&ids=121,11,111,147,6,116,149,7,119,109&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:38477//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 987287 INFO (qtp1780084836-12092) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:38477//collection1&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 987288 INFO (qtp1695081451-12075) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=a_i_ni_p+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 987290 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i_ni_p+desc&shard.url=http://127.0.0.1:38477//collection1&rows=10&version=2&q=*:*&NOW=1521618193879&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 987291 INFO (qtp1780084836-12090) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521618193879&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:38477//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 987292 INFO (qtp1780084836-12105) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:38477//collection1&sort=a_i_ni_p+desc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 987295 INFO (qtp1695081451-12073) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i_ni_p,5))+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 987297 INFO (qtp1780084836-12106) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&dist
[...truncated too long message...]
utor-4118-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=371773619
[junit4] 2> 1005780 INFO (coreCloseExecutor-4118-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@56af2141: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1303a4ab
[junit4] 2> 1005793 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5b288b4c{/,null,UNAVAILABLE}
[junit4] 2> 1005793 INFO (TEST-TestDistributedSearch.test-seed#[BA3E1CFA45D25DB9]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=BA3E1CFA45D25DB9 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-NC -Dtests.timezone=Etc/GMT-8 -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 20.1s J2 | 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:273)
[junit4] > at org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:175)
[junit4] > at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] > at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514)
[junit4] > at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[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:188)
[junit4] > at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
[junit4] > at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] > ,time=2}
[junit4] > at __randomizedtesting.SeedInfo.seed([BA3E1CFA45D25DB9:326A2320EB2E3041]:0)
[junit4] > at org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1191)
[junit4] > at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1132)
[junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:992)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1019)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_BA3E1CFA45D25DB9-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {other_tl1=FSTOrd50, range_facet_l_dv=PostingsFormat(name=MockRandom), SubjectTerms_mfacet=FSTOrd50, n_l1=FSTOrd50, a_n_tdt=PostingsFormat(name=MockRandom), multiDefault=FST50, intDefault=FSTOrd50, n_td1=FST50, n_d1=FSTOrd50, n_f1=FST50, range_facet_l=FSTOrd50, n_tl1=FST50, a_s=Lucene50(blocksize=128), oddField_s=Lucene50(blocksize=128), n_tf1=FSTOrd50, lowerfilt1and2=FST50, a_t=FSTOrd50, lowerfilt=FST50, id=PostingsFormat(name=MockRandom), text=Lucene50(blocksize=128), timestamp=FSTOrd50, severity=Lucene50(blocksize=128), foo_sev_enum=PostingsFormat(name=MockRandom), n_dt1=FST50, b_n_tdt=FSTOrd50, n_ti1=PostingsFormat(name=MockRandom), rnd_b=PostingsFormat(name=MockRandom), foo_b=Lucene50(blocksize=128), n_tdt1=FSTOrd50, foo_d=PostingsFormat(name=MockRandom), id_i1=FST50, range_facet_i_dv=FSTOrd50, foo_f=Lucene50(blocksize=128)}, docValues:{severity=DocValuesFormat(name=Lucene70), range_facet_l_dv=DocValuesFormat(name=Asserting), foo_sev_enum=DocValuesFormat(name=Asserting), _version_=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Direct), intDvoDefault=DocValuesFormat(name=Lucene70), a_i_ni_p=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=51, maxMBSortInHeap=7.387982793361925, sim=RandomSimilarity(queryNorm=true): {}, locale=fr-NC, timezone=Etc/GMT-8
[junit4] 2> NOTE: Linux 4.13.0-36-generic amd64/Oracle Corporation 9.0.4 (64-bit)/cpus=8,threads=2,free=260589776,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [HttpSolrCallGetCoreTest, TestFieldCacheSortRandom, TestSolr4Spatial2, SpellCheckComponentTest, TestHdfsUpdateLog, TestMacros, TestTolerantSearch, CachingDirectoryFactoryTest, TestMultiValuedNumericRangeQuery, NodeMutatorTest, DocValuesMultiTest, SolrIndexMetricsTest, TestFieldCache, TermVectorComponentTest, ClusterStateTest, BlockCacheTest, TestGeoJSONResponseWriter, QueryResultKeyTest, TestDeleteCollectionOnDownNodes, SolrCmdDistributorTest, UpdateLogTest, DateFieldTest, NotRequiredUniqueKeyTest, TestDistribStateManager, TestLeaderElectionZkExpiry, TestBinaryResponseWriter, TestFilteredDocIdSet, ClusterStateUpdateTest, SolrXmlInZkTest, TestLargeCluster, RecoveryZkTest, ZkStateWriterTest, BitVectorTest, TestHdfsBackupRestoreCore, PingRequestHandlerTest, AsyncCallRequestStatusResponseTest, DistributedFacetPivotLongTailTest, TestFiltering, ConvertedLegacyTest, FileBasedSpellCheckerTest, TestShardHandlerFactory, TestRealTimeGet, TestLazyCores, TestSchemaResource, BlockJoinFacetSimpleTest, TestSSLRandomization, TlogReplayBufferedWhileIndexingTest, CdcrVersionReplicationTest, SolrGraphiteReporterTest, TestComplexPhraseQParserPlugin, MetricUtilsTest, TestDFRSimilarityFactory, TestLRUStatsCache, TimeZoneUtilsTest, TestQuerySenderListener, TestSolrXml, TestUnifiedSolrHighlighter, TestJettySolrRunner, DeleteLastCustomShardedReplicaTest, TestConfigSetsAPIExclusivity, TestFileDictionaryLookup, CollectionsAPIDistributedZkTest, TestConfigSetImmutable, RegexBytesRefFilterTest, DocValuesTest, PreAnalyzedUpdateProcessorTest, TestMissingGroups, LIRRollingUpdatesTest, SolrSlf4jReporterTest, DistributedSpellCheckComponentTest, TestFastWriter, TestJmxIntegration, TestFieldTypeCollectionResource, DistributedExpandComponentTest, LeaderElectionIntegrationTest, TestBlobHandler, TestQueryTypes, RecoveryAfterSoftCommitTest, SpellPossibilityIteratorTest, MetricsConfigTest, TestNamedUpdateProcessors, TestIntervalFaceting, TestMaxTokenLenTokenizer, CircularListTest, BasicDistributedZk2Test, TestSubQueryTransformer, ReplaceNodeTest, TestCloudSearcherWarming, IndexSchemaRuntimeFieldTest, BJQParserTest, TestCoreDiscovery, NumericFieldsTest, TestJsonRequest, TestValueSourceCache, DeleteShardTest, TestTrackingShardHandlerFactory, SuggestComponentContextFilterQueryTest, DistributedFacetPivotWhiteBoxTest, HdfsDirectoryTest, SearchHandlerTest, TestManagedSynonymFilterFactory, MultiTermTest, HdfsRestartWhileUpdatingTest, TestRandomDVFaceting, DateRangeFieldTest, TestXmlQParser, ConcurrentCreateRoutedAliasTest, AutoAddReplicasIntegrationTest, CacheHeaderTest, TestCodecSupport, TestRestoreCore, NoCacheHeaderTest, LukeRequestHandlerTest, TestManagedResource, TestQueryWrapperFilter, DistanceFunctionTest, ReplicaListTransformerTest, TestUtils, HLLSerializationTest, AtomicUpdateProcessorFactoryTest, TestSolrDeletionPolicy2, CoreAdminCreateDiscoverTest, RulesTest, TestAnalyzedSuggestions, TestRetrieveFieldsOptimizer, TestDynamicLoading, TestLocalFSCloudBackupRestore, LeaderElectionTest, TestDistributedSearch]
[junit4] Completed [297/784 (1!)] on J2 in 20.13s, 1 test, 1 failure <<< FAILURES!
[...truncated 44390 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1568/consoleText
[repro] Revision: 32c8540d56add6fbf1906225f58afb78b7f9983c
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=BA3E1CFA45D25DB9 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-NC -Dtests.timezone=Etc/GMT-8 -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 3315 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestDistributedSearch" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=BA3E1CFA45D25DB9 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-NC -Dtests.timezone=Etc/GMT-8 -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 79 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.TestDistributedSearch
[repro] Exiting with code 0
[...truncated 40 lines...]