You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/08/02 08:34:16 UTC
[JENKINS-EA] Lucene-Solr-master-Linux (64bit/jdk-9-ea+178) - Build
# 20244 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20244/
Java: 64bit/jdk-9-ea+178 -XX:+UseCompressedOops -XX:+UseG1GC --illegal-access=deny
1 tests failed.
FAILED: org.apache.solr.TestDistributedSearch.test
Error Message:
.responseHeader.status!=warnings (unordered or missing)
Stack Trace:
junit.framework.AssertionFailedError: .responseHeader.status!=warnings (unordered or missing)
at __randomizedtesting.SeedInfo.seed([A9CB2F3CB3D6B43F:219F10E61D2AD9C7]:0)
at junit.framework.Assert.fail(Assert.java:50)
at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:913)
at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:932)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:607)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:587)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:566)
at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:241)
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:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1011)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
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:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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 11769 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A9CB2F3CB3D6B43F-001/init-core-data-001
[junit4] 2> 1038791 WARN (SUITE-TestDistributedSearch-seed#[A9CB2F3CB3D6B43F]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 1038792 INFO (SUITE-TestDistributedSearch-seed#[A9CB2F3CB3D6B43F]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1038792 INFO (SUITE-TestDistributedSearch-seed#[A9CB2F3CB3D6B43F]-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> 1038792 INFO (SUITE-TestDistributedSearch-seed#[A9CB2F3CB3D6B43F]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1038793 INFO (SUITE-TestDistributedSearch-seed#[A9CB2F3CB3D6B43F]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /ro_jnr/
[junit4] 2> 1038871 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A9CB2F3CB3D6B43F-001/tempDir-001/control/cores/collection1
[junit4] 2> 1038871 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1038872 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1944868b{/ro_jnr,null,AVAILABLE}
[junit4] 2> 1038874 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6e352da8{HTTP/1.1,[http/1.1]}{127.0.0.1:40951}
[junit4] 2> 1038874 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.e.j.s.Server Started @1040585ms
[junit4] 2> 1038874 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ro_jnr, hostPort=40951, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A9CB2F3CB3D6B43F-001/tempDir-001/control/cores}
[junit4] 2> 1038874 ERROR (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1038874 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 1038874 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1038874 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 1038874 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-02T08:06:42.288494Z
[junit4] 2> 1038874 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A9CB2F3CB3D6B43F-001/tempDir-001/control/solr.xml
[junit4] 2> 1038876 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2054fa50, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1038895 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2054fa50
[junit4] 2> 1038898 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2054fa50
[junit4] 2> 1038898 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2054fa50
[junit4] 2> 1038899 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A9CB2F3CB3D6B43F-001/tempDir-001/control/cores
[junit4] 2> 1038899 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1038899 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1038913 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1038933 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1038989 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1038993 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1038993 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2054fa50
[junit4] 2> 1038993 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1038993 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A9CB2F3CB3D6B43F-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 1038994 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1039018 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1039018 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1039018 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1039019 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1039019 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=27, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1039019 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7e417123[collection1] main]
[junit4] 2> 1039020 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1039032 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1039088 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1039088 INFO (searcherExecutor-4442-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1039088 INFO (coreLoadExecutor-4441-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1574605897074737152
[junit4] 2> 1039088 INFO (searcherExecutor-4442-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1039089 INFO (searcherExecutor-4442-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1039089 INFO (searcherExecutor-4442-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1039089 INFO (searcherExecutor-4442-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1039089 INFO (searcherExecutor-4442-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1039089 INFO (searcherExecutor-4442-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1039089 INFO (searcherExecutor-4442-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1039090 INFO (searcherExecutor-4442-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1039091 INFO (searcherExecutor-4442-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7e417123[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1039486 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A9CB2F3CB3D6B43F-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 1039487 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1039488 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@28153f34{/ro_jnr,null,AVAILABLE}
[junit4] 2> 1039488 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@730c7c82{HTTP/1.1,[http/1.1]}{127.0.0.1:44013}
[junit4] 2> 1039488 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.e.j.s.Server Started @1041199ms
[junit4] 2> 1039488 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ro_jnr, hostPort=44013, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A9CB2F3CB3D6B43F-001/tempDir-001/shard0/cores}
[junit4] 2> 1039489 ERROR (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1039489 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 1039489 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1039489 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 1039489 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-02T08:06:42.903176Z
[junit4] 2> 1039489 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A9CB2F3CB3D6B43F-001/tempDir-001/shard0/solr.xml
[junit4] 2> 1039490 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2054fa50, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1039504 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2054fa50
[junit4] 2> 1039507 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2054fa50
[junit4] 2> 1039507 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2054fa50
[junit4] 2> 1039508 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A9CB2F3CB3D6B43F-001/tempDir-001/shard0/cores
[junit4] 2> 1039508 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1039508 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1039521 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1039540 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1039626 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1039630 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1039631 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2054fa50
[junit4] 2> 1039631 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1039631 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A9CB2F3CB3D6B43F-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 1039632 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1039659 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1039659 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1039659 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1039659 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1039660 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=27, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1039660 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@507f3205[collection1] main]
[junit4] 2> 1039660 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1039672 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1039733 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1039733 INFO (searcherExecutor-4453-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1039733 INFO (coreLoadExecutor-4452-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1574605897751068672
[junit4] 2> 1039733 INFO (searcherExecutor-4453-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1039733 INFO (searcherExecutor-4453-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1039733 INFO (searcherExecutor-4453-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1039734 INFO (searcherExecutor-4453-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1039734 INFO (searcherExecutor-4453-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1039734 INFO (searcherExecutor-4453-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1039734 INFO (searcherExecutor-4453-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1039735 INFO (searcherExecutor-4453-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1039736 INFO (searcherExecutor-4453-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@507f3205[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1040019 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1040023 INFO (qtp1099024741-16750) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1574605898053058560)} 0 2
[junit4] 2> 1040025 INFO (qtp1040277792-16767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1574605898056204288)} 0 0
[junit4] 2> 1040028 INFO (qtp1099024741-16751) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[1 (1574605898058301440)]} 0 1
[junit4] 2> 1040029 INFO (qtp1040277792-16768) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[1 (1574605898061447168)]} 0 0
[junit4] 2> 1040031 INFO (qtp1099024741-16752) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[2 (1574605898062495744)]} 0 0
[junit4] 2> 1040032 INFO (qtp1040277792-16769) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[2 (1574605898063544320)]} 0 0
[junit4] 2> 1040033 INFO (qtp1099024741-16745) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[3 (1574605898064592896)]} 0 0
[junit4] 2> 1040034 INFO (qtp1040277792-16762) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[3 (1574605898065641472)]} 0 0
[junit4] 2> 1040035 INFO (qtp1099024741-16750) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[4 (1574605898066690048)]} 0 0
[junit4] 2> 1040036 INFO (qtp1040277792-16767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[4 (1574605898067738624)]} 0 0
[junit4] 2> 1040037 INFO (qtp1099024741-16751) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[5 (1574605898068787200)]} 0 0
[junit4] 2> 1040038 INFO (qtp1040277792-16768) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[5 (1574605898069835776)]} 0 0
[junit4] 2> 1040038 INFO (qtp1099024741-16752) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[6 (1574605898070884352)]} 0 0
[junit4] 2> 1040039 INFO (qtp1040277792-16769) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[6 (1574605898071932928)]} 0 0
[junit4] 2> 1040040 INFO (qtp1099024741-16745) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[7 (1574605898072981504)]} 0 0
[junit4] 2> 1040041 INFO (qtp1040277792-16762) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[7 (1574605898074030080)]} 0 0
[junit4] 2> 1040042 INFO (qtp1099024741-16750) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[8 (1574605898075078656)]} 0 0
[junit4] 2> 1040043 INFO (qtp1040277792-16767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[8 (1574605898075078656)]} 0 0
[junit4] 2> 1040044 INFO (qtp1099024741-16751) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[9 (1574605898076127232)]} 0 0
[junit4] 2> 1040044 INFO (qtp1040277792-16768) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[9 (1574605898077175808)]} 0 0
[junit4] 2> 1040045 INFO (qtp1099024741-16752) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574605898078224384,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1040045 INFO (qtp1099024741-16752) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1ce60b55 commitCommandVersion:1574605898078224384
[junit4] 2> 1040055 INFO (qtp1099024741-16752) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@799e4c7b[collection1] main]
[junit4] 2> 1040056 INFO (qtp1099024741-16752) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1040057 INFO (searcherExecutor-4442-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@799e4c7b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C9)))}
[junit4] 2> 1040057 INFO (qtp1099024741-16752) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 12
[junit4] 2> 1040058 INFO (qtp1040277792-16769) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574605898091855872,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1040058 INFO (qtp1040277792-16769) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@250abe8c commitCommandVersion:1574605898091855872
[junit4] 2> 1040064 INFO (qtp1040277792-16769) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@ed2115d[collection1] main]
[junit4] 2> 1040065 INFO (qtp1040277792-16769) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1040066 INFO (searcherExecutor-4453-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@ed2115d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C9)))}
[junit4] 2> 1040067 INFO (qtp1040277792-16769) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 8
[junit4] 2> 1040069 INFO (qtp1099024741-16745) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[10 (1574605898101293056)]} 0 2
[junit4] 2> 1040072 INFO (qtp1040277792-16762) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[10 (1574605898104438784)]} 0 1
[junit4] 2> 1040073 INFO (qtp1099024741-16750) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[11 (1574605898106535936)]} 0 0
[junit4] 2> 1040073 INFO (qtp1040277792-16767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[11 (1574605898107584512)]} 0 0
[junit4] 2> 1040074 INFO (qtp1099024741-16751) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[12 (1574605898108633088)]} 0 0
[junit4] 2> 1040075 INFO (qtp1040277792-16768) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[12 (1574605898109681664)]} 0 0
[junit4] 2> 1040076 INFO (qtp1099024741-16752) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[13 (1574605898110730240)]} 0 0
[junit4] 2> 1040077 INFO (qtp1040277792-16769) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[13 (1574605898110730240)]} 0 0
[junit4] 2> 1040078 INFO (qtp1099024741-16745) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[1001 (1574605898111778816)]} 0 0
[junit4] 2> 1040079 INFO (qtp1040277792-16762) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[1001 (1574605898113875968)]} 0 0
[junit4] 2> 1040080 INFO (qtp1099024741-16750) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[14 (1574605898114924544)]} 0 0
[junit4] 2> 1040081 INFO (qtp1040277792-16767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[14 (1574605898114924544)]} 0 0
[junit4] 2> 1040081 INFO (qtp1099024741-16751) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[15 (1574605898115973120)]} 0 0
[junit4] 2> 1040082 INFO (qtp1040277792-16768) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[15 (1574605898117021696)]} 0 0
[junit4] 2> 1040083 INFO (qtp1099024741-16752) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[16 (1574605898118070272)]} 0 0
[junit4] 2> 1040084 INFO (qtp1040277792-16769) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[16 (1574605898119118848)]} 0 0
[junit4] 2> 1040085 INFO (qtp1099024741-16745) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[17 (1574605898119118848)]} 0 0
[junit4] 2> 1040086 INFO (qtp1040277792-16762) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[17 (1574605898120167424)]} 0 0
[junit4] 2> 1040087 INFO (qtp1099024741-16750) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[100 (1574605898121216000)]} 0 0
[junit4] 2> 1040087 INFO (qtp1040277792-16767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[100 (1574605898122264576)]} 0 0
[junit4] 2> 1040088 INFO (qtp1099024741-16751) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[101 (1574605898123313152)]} 0 0
[junit4] 2> 1040089 INFO (qtp1040277792-16768) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[101 (1574605898123313152)]} 0 0
[junit4] 2> 1040089 INFO (qtp1099024741-16752) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[102 (1574605898124361728)]} 0 0
[junit4] 2> 1040090 INFO (qtp1040277792-16769) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[102 (1574605898125410304)]} 0 0
[junit4] 2> 1040091 INFO (qtp1099024741-16745) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[103 (1574605898126458880)]} 0 0
[junit4] 2> 1040092 INFO (qtp1040277792-16762) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[103 (1574605898126458880)]} 0 0
[junit4] 2> 1040092 INFO (qtp1099024741-16750) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[104 (1574605898127507456)]} 0 0
[junit4] 2> 1040093 INFO (qtp1040277792-16767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[104 (1574605898128556032)]} 0 0
[junit4] 2> 1040094 INFO (qtp1099024741-16751) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[105 (1574605898128556032)]} 0 0
[junit4] 2> 1040094 INFO (qtp1040277792-16768) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[105 (1574605898129604608)]} 0 0
[junit4] 2> 1040095 INFO (qtp1099024741-16752) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[106 (1574605898130653184)]} 0 0
[junit4] 2> 1040096 INFO (qtp1040277792-16769) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[106 (1574605898131701760)]} 0 0
[junit4] 2> 1040097 INFO (qtp1099024741-16745) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[107 (1574605898131701760)]} 0 0
[junit4] 2> 1040097 INFO (qtp1040277792-16762) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[107 (1574605898132750336)]} 0 0
[junit4] 2> 1040098 INFO (qtp1099024741-16750) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[108 (1574605898133798912)]} 0 0
[junit4] 2> 1040099 INFO (qtp1040277792-16767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[108 (1574605898134847488)]} 0 0
[junit4] 2> 1040100 INFO (qtp1099024741-16751) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[109 (1574605898135896064)]} 0 0
[junit4] 2> 1040101 INFO (qtp1040277792-16768) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[109 (1574605898135896064)]} 0 0
[junit4] 2> 1040101 INFO (qtp1099024741-16752) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[110 (1574605898136944640)]} 0 0
[junit4] 2> 1040102 INFO (qtp1040277792-16769) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[110 (1574605898137993216)]} 0 0
[junit4] 2> 1040103 INFO (qtp1099024741-16745) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[111 (1574605898137993216)]} 0 0
[junit4] 2> 1040103 INFO (qtp1040277792-16762) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[111 (1574605898139041792)]} 0 0
[junit4] 2> 1040104 INFO (qtp1099024741-16750) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[112 (1574605898140090368)]} 0 0
[junit4] 2> 1040105 INFO (qtp1040277792-16767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[112 (1574605898141138944)]} 0 0
[junit4] 2> 1040106 INFO (qtp1099024741-16751) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[113 (1574605898141138944)]} 0 0
[junit4] 2> 1040106 INFO (qtp1040277792-16768) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[113 (1574605898142187520)]} 0 0
[junit4] 2> 1040107 INFO (qtp1099024741-16752) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[114 (1574605898143236096)]} 0 0
[junit4] 2> 1040108 INFO (qtp1040277792-16769) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[114 (1574605898143236096)]} 0 0
[junit4] 2> 1040108 INFO (qtp1099024741-16745) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[115 (1574605898144284672)]} 0 0
[junit4] 2> 1040109 INFO (qtp1040277792-16762) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[115 (1574605898145333248)]} 0 0
[junit4] 2> 1040110 INFO (qtp1099024741-16750) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[116 (1574605898146381824)]} 0 0
[junit4] 2> 1040111 INFO (qtp1040277792-16767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[116 (1574605898146381824)]} 0 0
[junit4] 2> 1040111 INFO (qtp1099024741-16751) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[117 (1574605898147430400)]} 0 0
[junit4] 2> 1040112 INFO (qtp1040277792-16768) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[117 (1574605898148478976)]} 0 0
[junit4] 2> 1040113 INFO (qtp1099024741-16752) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[118 (1574605898148478976)]} 0 0
[junit4] 2> 1040113 INFO (qtp1040277792-16769) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[118 (1574605898149527552)]} 0 0
[junit4] 2> 1040114 INFO (qtp1099024741-16745) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[119 (1574605898150576128)]} 0 0
[junit4] 2> 1040115 INFO (qtp1040277792-16762) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[119 (1574605898151624704)]} 0 0
[junit4] 2> 1040116 INFO (qtp1099024741-16750) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[120 (1574605898152673280)]} 0 0
[junit4] 2> 1040116 INFO (qtp1040277792-16767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[120 (1574605898152673280)]} 0 0
[junit4] 2> 1040117 INFO (qtp1099024741-16751) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[121 (1574605898153721856)]} 0 0
[junit4] 2> 1040118 INFO (qtp1040277792-16768) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[121 (1574605898154770432)]} 0 0
[junit4] 2> 1040119 INFO (qtp1099024741-16752) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[122 (1574605898154770432)]} 0 0
[junit4] 2> 1040119 INFO (qtp1040277792-16769) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[122 (1574605898155819008)]} 0 0
[junit4] 2> 1040120 INFO (qtp1099024741-16745) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[123 (1574605898156867584)]} 0 0
[junit4] 2> 1040121 INFO (qtp1040277792-16762) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[123 (1574605898156867584)]} 0 0
[junit4] 2> 1040121 INFO (qtp1099024741-16750) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[124 (1574605898157916160)]} 0 0
[junit4] 2> 1040122 INFO (qtp1040277792-16767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[124 (1574605898158964736)]} 0 0
[junit4] 2> 1040123 INFO (qtp1099024741-16751) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[125 (1574605898158964736)]} 0 0
[junit4] 2> 1040123 INFO (qtp1040277792-16768) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[125 (1574605898160013312)]} 0 0
[junit4] 2> 1040124 INFO (qtp1099024741-16752) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[126 (1574605898161061888)]} 0 0
[junit4] 2> 1040125 INFO (qtp1040277792-16769) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[126 (1574605898161061888)]} 0 0
[junit4] 2> 1040125 INFO (qtp1099024741-16745) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[127 (1574605898162110464)]} 0 0
[junit4] 2> 1040126 INFO (qtp1040277792-16762) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[127 (1574605898163159040)]} 0 0
[junit4] 2> 1040127 INFO (qtp1099024741-16750) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[128 (1574605898163159040)]} 0 0
[junit4] 2> 1040127 INFO (qtp1040277792-16767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[128 (1574605898164207616)]} 0 0
[junit4] 2> 1040128 INFO (qtp1099024741-16751) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[129 (1574605898165256192)]} 0 0
[junit4] 2> 1040128 INFO (qtp1040277792-16768) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[129 (1574605898165256192)]} 0 0
[junit4] 2> 1040129 INFO (qtp1099024741-16752) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[130 (1574605898166304768)]} 0 0
[junit4] 2> 1040129 INFO (qtp1040277792-16769) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[130 (1574605898166304768)]} 0 0
[junit4] 2> 1040130 INFO (qtp1099024741-16745) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[131 (1574605898167353344)]} 0 0
[junit4] 2> 1040130 INFO (qtp1040277792-16762) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[131 (1574605898167353344)]} 0 0
[junit4] 2> 1040131 INFO (qtp1099024741-16750) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[132 (1574605898168401920)]} 0 0
[junit4] 2> 1040132 INFO (qtp1040277792-16767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[132 (1574605898168401920)]} 0 0
[junit4] 2> 1040132 INFO (qtp1099024741-16751) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[133 (1574605898169450496)]} 0 0
[junit4] 2> 1040133 INFO (qtp1040277792-16768) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[133 (1574605898170499072)]} 0 0
[junit4] 2> 1040133 INFO (qtp1099024741-16752) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[134 (1574605898170499072)]} 0 0
[junit4] 2> 1040134 INFO (qtp1040277792-16769) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[134 (1574605898170499073)]} 0 0
[junit4] 2> 1040134 INFO (qtp1099024741-16745) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[135 (1574605898171547648)]} 0 0
[junit4] 2> 1040135 INFO (qtp1040277792-16762) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[135 (1574605898172596224)]} 0 0
[junit4] 2> 1040135 INFO (qtp1099024741-16750) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[136 (1574605898172596224)]} 0 0
[junit4] 2> 1040136 INFO (qtp1040277792-16767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[136 (1574605898173644800)]} 0 0
[junit4] 2> 1040136 INFO (qtp1099024741-16751) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[137 (1574605898173644800)]} 0 0
[junit4] 2> 1040137 INFO (qtp1040277792-16768) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[137 (1574605898173644801)]} 0 0
[junit4] 2> 1040137 INFO (qtp1099024741-16752) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[138 (1574605898174693376)]} 0 0
[junit4] 2> 1040137 INFO (qtp1040277792-16769) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[138 (1574605898174693376)]} 0 0
[junit4] 2> 1040138 INFO (qtp1099024741-16745) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[139 (1574605898175741952)]} 0 0
[junit4] 2> 1040138 INFO (qtp1040277792-16762) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[139 (1574605898175741952)]} 0 0
[junit4] 2> 1040139 INFO (qtp1099024741-16750) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[140 (1574605898176790528)]} 0 0
[junit4] 2> 1040139 INFO (qtp1040277792-16767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[140 (1574605898176790528)]} 0 0
[junit4] 2> 1040140 INFO (qtp1099024741-16751) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[141 (1574605898177839104)]} 0 0
[junit4] 2> 1040140 INFO (qtp1040277792-16768) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[141 (1574605898177839104)]} 0 0
[junit4] 2> 1040140 INFO (qtp1099024741-16752) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[142 (1574605898177839105)]} 0 0
[junit4] 2> 1040141 INFO (qtp1040277792-16769) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[142 (1574605898178887680)]} 0 0
[junit4] 2> 1040141 INFO (qtp1099024741-16745) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[143 (1574605898178887680)]} 0 0
[junit4] 2> 1040142 INFO (qtp1040277792-16762) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[143 (1574605898178887681)]} 0 0
[junit4] 2> 1040142 INFO (qtp1099024741-16750) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[144 (1574605898179936256)]} 0 0
[junit4] 2> 1040142 INFO (qtp1040277792-16767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[144 (1574605898179936256)]} 0 0
[junit4] 2> 1040143 INFO (qtp1099024741-16751) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[145 (1574605898180984832)]} 0 0
[junit4] 2> 1040143 INFO (qtp1040277792-16768) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[145 (1574605898180984832)]} 0 0
[junit4] 2> 1040144 INFO (qtp1099024741-16752) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[146 (1574605898180984833)]} 0 0
[junit4] 2> 1040144 INFO (qtp1040277792-16769) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[146 (1574605898182033408)]} 0 0
[junit4] 2> 1040144 INFO (qtp1099024741-16745) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[147 (1574605898182033408)]} 0 0
[junit4] 2> 1040145 INFO (qtp1040277792-16762) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[147 (1574605898183081984)]} 0 0
[junit4] 2> 1040145 INFO (qtp1099024741-16750) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[148 (1574605898183081984)]} 0 0
[junit4] 2> 1040145 INFO (qtp1040277792-16767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[148 (1574605898183081985)]} 0 0
[junit4] 2> 1040146 INFO (qtp1099024741-16751) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[149 (1574605898184130560)]} 0 0
[junit4] 2> 1040146 INFO (qtp1040277792-16768) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={wt=javabin&version=2}{add=[149 (1574605898184130560)]} 0 0
[junit4] 2> 1040146 INFO (qtp1099024741-16752) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574605898184130561,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1040146 INFO (qtp1099024741-16752) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1ce60b55 commitCommandVersion:1574605898184130561
[junit4] 2> 1040152 INFO (qtp1099024741-16752) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6d25be2e[collection1] main]
[junit4] 2> 1040152 INFO (qtp1099024741-16752) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1040153 INFO (searcherExecutor-4442-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6d25be2e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C9) Uninverting(_1(8.0.0):C59)))}
[junit4] 2> 1040153 INFO (qtp1099024741-16752) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 6
[junit4] 2> 1040154 INFO (qtp1040277792-16769) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574605898192519168,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1040154 INFO (qtp1040277792-16769) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@250abe8c commitCommandVersion:1574605898192519168
[junit4] 2> 1040160 INFO (qtp1040277792-16769) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4eb93cd8[collection1] main]
[junit4] 2> 1040160 INFO (qtp1040277792-16769) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1040161 INFO (searcherExecutor-4453-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4eb93cd8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C9) Uninverting(_1(8.0.0):C59)))}
[junit4] 2> 1040161 INFO (qtp1040277792-16769) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ro_jnr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 7
[junit4] 2> 1040162 INFO (qtp1099024741-16745) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 1040162 INFO (qtp1099024741-16745) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 1040163 INFO (qtp1099024741-16750) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 1040163 INFO (qtp1099024741-16750) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 1040163 INFO (qtp1099024741-16751) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040166 INFO (qtp1040277792-16768) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr 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:44013/ro_jnr/collection1&rows=10&version=2&q=*:*&NOW=1501661203578&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040167 INFO (qtp1040277792-16769) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203578&ids=12,123,13,102,146,126,127,117,7,118&isShard=true&shard.url=http://127.0.0.1:44013/ro_jnr/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040168 INFO (qtp1040277792-16762) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=http://127.0.0.1:44013/ro_jnr/collection1&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 1040169 INFO (qtp1099024741-16752) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040170 INFO (qtp1040277792-16763) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr 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:44013/ro_jnr/collection1&rows=10&version=2&q=*:*&NOW=1501661203583&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040171 INFO (qtp1040277792-16769) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203583&ids=3,4,137,104,149,17,128,8,107,130&isShard=true&shard.url=http://127.0.0.1:44013/ro_jnr/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040172 INFO (qtp1040277792-16767) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=http://127.0.0.1:44013/ro_jnr/collection1&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 1040172 INFO (qtp1099024741-16745) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040187 INFO (qtp1040277792-16762) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::114]:33332/ro_jnr|[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&rows=10&version=2&q=*:*&NOW=1501661203587&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040188 INFO (qtp1040277792-16769) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203587&ids=144,145,136,148,5,138,116,1001,9,109&isShard=true&shard.url=[ff01::114]:33332/ro_jnr|[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040188 INFO (qtp1040277792-16768) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=[ff01::114]:33332/ro_jnr|[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=15
[junit4] 2> 1040189 INFO (qtp1099024741-16750) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040197 INFO (qtp1040277792-16767) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=[ff01::083]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&rows=10&version=2&q=*:*&NOW=1501661203604&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040198 INFO (qtp1040277792-16769) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203604&ids=121,133,1,122,135,146,15,126,139,130&isShard=true&shard.url=[ff01::083]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040198 INFO (qtp1040277792-16763) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=[ff01::083]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=8
[junit4] 2> 1040199 INFO (qtp1099024741-16751) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040200 INFO (qtp1040277792-16768) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:44013/ro_jnr/collection1|[ff01::083]:33332/ro_jnr&rows=10&version=2&q=*:*&NOW=1501661203614&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040201 INFO (qtp1040277792-16769) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203614&ids=12,111,145,146,115,148,127,107,119,142&isShard=true&shard.url=http://127.0.0.1:44013/ro_jnr/collection1|[ff01::083]:33332/ro_jnr&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040201 INFO (qtp1040277792-16762) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=http://127.0.0.1:44013/ro_jnr/collection1|[ff01::083]:33332/ro_jnr&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1040202 INFO (qtp1099024741-16752) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040203 INFO (qtp1040277792-16763) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr 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:44013/ro_jnr/collection1|[ff01::114]:33332/ro_jnr&rows=10&version=2&q=*:*&NOW=1501661203616&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040203 INFO (qtp1040277792-16769) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203616&ids=122,100,101,123,136,16,126,105,139,108&isShard=true&shard.url=http://127.0.0.1:44013/ro_jnr/collection1|[ff01::114]:33332/ro_jnr&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040204 INFO (qtp1040277792-16767) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=http://127.0.0.1:44013/ro_jnr/collection1|[ff01::114]:33332/ro_jnr&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1040204 INFO (qtp1099024741-16745) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040205 INFO (qtp1040277792-16762) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=http://127.0.0.1:44013/ro_jnr/collection1|[ff01::213]:33332/ro_jnr&rows=10&version=2&q=*:*&NOW=1501661203619&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040206 INFO (qtp1040277792-16769) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203619&ids=110,2,112,148,127,117,7,119,130,10&isShard=true&shard.url=http://127.0.0.1:44013/ro_jnr/collection1|[ff01::213]:33332/ro_jnr&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040207 INFO (qtp1040277792-16768) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=http://127.0.0.1:44013/ro_jnr/collection1|[ff01::213]:33332/ro_jnr&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1040207 INFO (qtp1099024741-16750) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040208 INFO (qtp1040277792-16767) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:44013/ro_jnr/collection1|[ff01::213]:33332/ro_jnr&rows=10&version=2&q=*:*&NOW=1501661203622&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040209 INFO (qtp1040277792-16769) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203622&ids=111,146,14,125,147,114,16,8,108,131&isShard=true&shard.url=http://127.0.0.1:44013/ro_jnr/collection1|[ff01::213]:33332/ro_jnr&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040210 INFO (qtp1040277792-16763) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=http://127.0.0.1:44013/ro_jnr/collection1|[ff01::213]:33332/ro_jnr&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 1040210 INFO (qtp1099024741-16751) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040212 INFO (qtp1040277792-16768) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&rows=10&version=2&q=*:*&NOW=1501661203625&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040213 INFO (qtp1040277792-16769) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203625&ids=122,134,147,136,128,139,7,118,9,130&isShard=true&shard.url=[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040214 INFO (qtp1040277792-16762) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 1040215 INFO (qtp1099024741-16751) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040216 INFO (qtp1040277792-16763) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::213]:33332/ro_jnr|[ff01::114]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&rows=10&version=2&q=*:*&NOW=1501661203629&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040216 INFO (qtp1040277792-16769) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203629&ids=123,102,15,4,16,17,127,149,106,107&isShard=true&shard.url=[ff01::213]:33332/ro_jnr|[ff01::114]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040217 INFO (qtp1040277792-16767) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=[ff01::213]:33332/ro_jnr|[ff01::114]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1040218 INFO (qtp1099024741-16745) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040219 INFO (qtp1040277792-16762) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=http://127.0.0.1:44013/ro_jnr/collection1|[ff01::083]:33332/ro_jnr&rows=10&version=2&q=*:*&NOW=1501661203633&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040220 INFO (qtp1040277792-16769) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203633&ids=121,12,124,14,136,125,8,119,130,131&isShard=true&shard.url=http://127.0.0.1:44013/ro_jnr/collection1|[ff01::083]:33332/ro_jnr&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040221 INFO (qtp1040277792-16768) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=http://127.0.0.1:44013/ro_jnr/collection1|[ff01::083]:33332/ro_jnr&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1040221 INFO (qtp1099024741-16750) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040222 INFO (qtp1040277792-16767) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1|[ff01::114]:33332/ro_jnr&rows=10&version=2&q=*:*&NOW=1501661203636&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040223 INFO (qtp1040277792-16769) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203636&ids=132,13,3,147,148,116,106,109,142,10&isShard=true&shard.url=[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1|[ff01::114]:33332/ro_jnr&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040224 INFO (qtp1040277792-16763) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1|[ff01::114]:33332/ro_jnr&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1040224 INFO (qtp1099024741-16752) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040226 INFO (qtp1040277792-16768) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=[ff01::083]:33332/ro_jnr|[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&rows=10&version=2&q=*:*&NOW=1501661203639&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040227 INFO (qtp1040277792-16769) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203639&ids=132,110,1,122,112,136,126,128,9,140&isShard=true&shard.url=[ff01::083]:33332/ro_jnr|[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040227 INFO (qtp1040277792-16762) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=[ff01::083]:33332/ro_jnr|[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1040228 INFO (qtp1099024741-16751) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040229 INFO (qtp1040277792-16763) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=[ff01::083]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&rows=10&version=2&q=*:*&NOW=1501661203643&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040230 INFO (qtp1040277792-16769) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203643&ids=133,2,3,113,138,149,17,117,130,131&isShard=true&shard.url=[ff01::083]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040230 INFO (qtp1040277792-16767) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=[ff01::083]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1040231 INFO (qtp1099024741-16745) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040232 INFO (qtp1040277792-16762) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr 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/ro_jnr|[ff01::213]:33332/ro_jnr|[ff01::114]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&rows=10&version=2&q=*:*&NOW=1501661203646&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040233 INFO (qtp1040277792-16762) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203646&ids=121,12,123,145,13,135,15,103,128,7&isShard=true&shard.url=[ff01::083]:33332/ro_jnr|[ff01::213]:33332/ro_jnr|[ff01::114]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040233 INFO (qtp1040277792-16768) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=[ff01::083]:33332/ro_jnr|[ff01::213]:33332/ro_jnr|[ff01::114]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1040234 INFO (qtp1099024741-16750) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040235 INFO (qtp1040277792-16767) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=[ff01::083]:33332/ro_jnr|[ff01::114]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1|[ff01::213]:33332/ro_jnr&rows=10&version=2&q=*:*&NOW=1501661203649&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040236 INFO (qtp1040277792-16762) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203649&ids=143,144,104,148,16,116,117,1001,139,140&isShard=true&shard.url=[ff01::083]:33332/ro_jnr|[ff01::114]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1|[ff01::213]:33332/ro_jnr&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040237 INFO (qtp1040277792-16763) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=[ff01::083]:33332/ro_jnr|[ff01::114]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1|[ff01::213]:33332/ro_jnr&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1040237 INFO (qtp1099024741-16752) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040239 INFO (qtp1040277792-16768) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=[ff01::114]:33332/ro_jnr|[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&rows=10&version=2&q=*:*&NOW=1501661203652&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040239 INFO (qtp1040277792-16762) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203652&ids=132,122,124,102,146,16,6,117,128,10&isShard=true&shard.url=[ff01::114]:33332/ro_jnr|[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040240 INFO (qtp1040277792-16769) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=[ff01::114]:33332/ro_jnr|[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1040241 INFO (qtp1099024741-16751) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040242 INFO (qtp1040277792-16763) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+asc&shard.url=[ff01::213]:33332/ro_jnr|[ff01::114]:33332/ro_jnr|[ff01::083]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&rows=10&version=2&q=*:*&NOW=1501661203655&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040243 INFO (qtp1040277792-16762) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203655&ids=13,2,134,4,15,138,105,9,119,140&isShard=true&shard.url=[ff01::213]:33332/ro_jnr|[ff01::114]:33332/ro_jnr|[ff01::083]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040243 INFO (qtp1040277792-16767) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=[ff01::213]:33332/ro_jnr|[ff01::114]:33332/ro_jnr|[ff01::083]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1040244 INFO (qtp1099024741-16745) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=a_i1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040245 INFO (qtp1040277792-16769) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=http://127.0.0.1:44013/ro_jnr/collection1&rows=10&version=2&q=*:*&NOW=1501661203659&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040246 INFO (qtp1040277792-16762) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203659&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:44013/ro_jnr/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040247 INFO (qtp1040277792-16768) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=http://127.0.0.1:44013/ro_jnr/collection1&sort=a_i1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 1040249 INFO (qtp1099024741-16745) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1040251 INFO (qtp1040277792-16767) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort={!func}testfunc(add(a_i1,5))+desc&shard.url=[ff01::213]:33332/ro_jnr|[ff01::083]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&rows=10&version=2&q=*:*&NOW=1501661203664&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040252 INFO (qtp1040277792-16762) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203664&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::213]:33332/ro_jnr|[ff01::083]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040253 INFO (qtp1040277792-16763) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=[ff01::213]:33332/ro_jnr|[ff01::083]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 1040255 INFO (qtp1099024741-16752) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=a_i1[*+TO+*]&distrib=false&sort=a_i1+asc&wt=javabin&version=2} hits=13 status=0 QTime=1
[junit4] 2> 1040257 INFO (qtp1040277792-16768) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+asc&shard.url=[ff01::083]:33332/ro_jnr|[ff01::114]:33332/ro_jnr|[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&rows=10&version=2&q=a_i1[*+TO+*]&NOW=1501661203670&isShard=true&wt=javabin} hits=13 status=0 QTime=0
[junit4] 2> 1040258 INFO (qtp1040277792-16762) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=a_i1[*+TO+*]&df=text&distrib=false&shards.purpose=64&NOW=1501661203670&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=[ff01::083]:33332/ro_jnr|[ff01::114]:33332/ro_jnr|[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040259 INFO (qtp1040277792-16769) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=a_i1[*+TO+*]&shards=[ff01::083]:33332/ro_jnr|[ff01::114]:33332/ro_jnr|[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&sort=a_i1+asc&wt=javabin&version=2} hits=13 status=0 QTime=3
[junit4] 2> 1040260 INFO (qtp1099024741-16751) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&sort=a_i1+asc,+id+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040262 INFO (qtp1040277792-16763) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+asc,+id+desc&shard.url=http://127.0.0.1:44013/ro_jnr/collection1|[ff01::083]:33332/ro_jnr|[ff01::114]:33332/ro_jnr&rows=10&version=2&q=*:*&NOW=1501661203675&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040263 INFO (qtp1040277792-16762) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501661203675&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=http://127.0.0.1:44013/ro_jnr/collection1|[ff01::083]:33332/ro_jnr|[ff01::114]:33332/ro_jnr&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040263 INFO (qtp1040277792-16767) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=http://127.0.0.1:44013/ro_jnr/collection1|[ff01::083]:33332/ro_jnr|[ff01::114]:33332/ro_jnr&sort=a_i1+asc,+id+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1040264 INFO (qtp1099024741-16750) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&fl=*,score&sort=a_i1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040265 INFO (qtp1040277792-16769) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=[ff01::083]:33332/ro_jnr|[ff01::114]:33332/ro_jnr|[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&rows=10&version=2&q=*:*&NOW=1501661203679&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040266 INFO (qtp1040277792-16762) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&fl=*,score&shards.purpose=64&NOW=1501661203679&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::083]:33332/ro_jnr|[ff01::114]:33332/ro_jnr|[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040267 INFO (qtp1040277792-16768) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=[ff01::083]:33332/ro_jnr|[ff01::114]:33332/ro_jnr|[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&fl=*,score&sort=a_i1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 1040267 INFO (qtp1099024741-16745) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&distrib=false&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1040268 INFO (qtp1040277792-16767) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&rows=10&version=2&q=*:*&NOW=1501661203682&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1040269 INFO (qtp1040277792-16762) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&df=text&distrib=false&fl=*,score&shards.purpose=64&NOW=1501661203682&ids=133,2,3,113,138,149,17,117,130,131&isShard=true&shard.url=[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1040270 INFO (qtp1040277792-16763) [ ] o.a.s.c.S.Request [collection1] webapp=/ro_jnr path=/select params={q=*:*&shards=[ff01::213]:33332/ro_jnr|http://127.0.0.1:44013/ro_jnr/collection1&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1040271 INFO (qtp1099024741-16752) [ ] o
[...truncated too long message...]
iner instance=1967712174
[junit4] 2> 1040325 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1040327 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1040329 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1040329 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1040329 INFO (coreCloseExecutor-4458-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3028798b
[junit4] 2> 1040330 INFO (coreCloseExecutor-4458-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=807958923
[junit4] 2> 1040334 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1944868b{/ro_jnr,null,UNAVAILABLE}
[junit4] 2> 1040335 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@730c7c82{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1040336 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1027479479
[junit4] 2> 1040336 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1040336 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1040336 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1040336 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1040336 INFO (coreCloseExecutor-4460-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3c9d3326
[junit4] 2> 1040337 INFO (coreCloseExecutor-4460-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1016935206
[junit4] 2> 1040344 INFO (TEST-TestDistributedSearch.test-seed#[A9CB2F3CB3D6B43F]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@28153f34{/ro_jnr,null,UNAVAILABLE}
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=A9CB2F3CB3D6B43F -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr-Cyrl-ME -Dtests.timezone=Europe/Skopje -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 1.55s J2 | TestDistributedSearch.test <<<
[junit4] > Throwable #1: junit.framework.AssertionFailedError: .responseHeader.status!=warnings (unordered or missing)
[junit4] > at __randomizedtesting.SeedInfo.seed([A9CB2F3CB3D6B43F:219F10E61D2AD9C7]:0)
[junit4] > at junit.framework.Assert.fail(Assert.java:50)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:913)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:932)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:607)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:587)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:566)
[junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:241)
[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:1011)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
[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-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A9CB2F3CB3D6B43F-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {rnd_b=BlockTreeOrds(blocksize=128), a_s=PostingsFormat(name=Direct), foo_sev_enum=PostingsFormat(name=Direct), SubjectTerms_mfacet=FST50, oddField_s=BlockTreeOrds(blocksize=128), multiDefault=PostingsFormat(name=Memory), lowerfilt1and2=PostingsFormat(name=Memory), a_t=PostingsFormat(name=Memory), foo_b=PostingsFormat(name=Direct), lowerfilt=PostingsFormat(name=Memory), id=BlockTreeOrds(blocksize=128), text=PostingsFormat(name=Direct)}, docValues:{other_tl1=DocValuesFormat(name=Memory), range_facet_l_dv=DocValuesFormat(name=Direct), foo_sev_enum=DocValuesFormat(name=Asserting), n_l1=DocValuesFormat(name=Lucene70), a_n_tdt=DocValuesFormat(name=Asserting), intDefault=DocValuesFormat(name=Memory), n_dt1=DocValuesFormat(name=Memory), a_i1=DocValuesFormat(name=Lucene70), n_td1=DocValuesFormat(name=Memory), b_n_tdt=DocValuesFormat(name=Memory), n_d1=DocValuesFormat(name=Lucene70), range_facet_l=DocValuesFormat(name=Lucene70), n_f1=DocValuesFormat(name=Memory), n_ti1=DocValuesFormat(name=Direct), n_tl1=DocValuesFormat(name=Memory), _version_=DocValuesFormat(name=Memory), n_tf1=DocValuesFormat(name=Lucene70), n_tdt1=DocValuesFormat(name=Memory), id_i1=DocValuesFormat(name=Memory), foo_d=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Lucene70), foo_f=DocValuesFormat(name=Asserting), intDvoDefault=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=534, maxMBSortInHeap=5.419982085551978, sim=RandomSimilarity(queryNorm=false): {}, locale=sr-Cyrl-ME, timezone=Europe/Skopje
[junit4] 2> NOTE: Linux 4.10.0-27-generic amd64/Oracle Corporation 9 (64-bit)/cpus=8,threads=1,free=119472952,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [DateMathParserTest, DateFieldTest, TestDFRSimilarityFactory, TestPayloadCheckQParserPlugin, TestPushWriter, TolerantUpdateProcessorTest, RestartWhileUpdatingTest, CdcrReplicationDistributedZkTest, VersionInfoTest, MissingSegmentRecoveryTest, TestReqParamsAPI, TestSolrXml, SolrShardReporterTest, BitVectorTest, TestXmlQParserPlugin, SaslZkACLProviderTest, TestSuggestSpellingConverter, TestJettySolrRunner, TestCollapseQParserPlugin, DocValuesMissingTest, TestSubQueryTransformerCrossCore, TestGraphTermsQParserPlugin, ResponseHeaderTest, TimeZoneUtilsTest, TestNumericTerms32, DeleteStatusTest, TestInitParams, TestIntervalFaceting, TestJmxIntegration, TestBulkSchemaConcurrent, TestTolerantSearch, SearchHandlerTest, PrimitiveFieldTypeTest, TestRecovery, TestDocBasedVersionConstraints, HdfsCollectionsAPIDistributedZkTest, TestCollectionsAPIViaSolrCloudCluster, SuggestComponentTest, TestSchemaSimilarityResource, TestMultiWordSynonyms, TestConfigSetsAPIZkFailure, HdfsThreadLeakTest, SolrCoreCheckLockOnStartupTest, TestHttpShardHandlerFactory, TestLegacyNumericUtils, ConfigSetsAPITest, TestCloudDeleteByQuery, SolrMetricsIntegrationTest, TestSegmentSorting, LeaderElectionTest, TestPseudoReturnFields, BlockJoinFacetSimpleTest, TestDistributedMissingSort, TestReload, RequiredFieldsTest, TestCustomSort, CircularListTest, TestNestedDocsSort, HdfsRecoveryZkTest, TestApiFramework, RequestLoggingTest, SimplePostToolTest, FastVectorHighlighterTest, MinimalSchemaTest, NumericFieldsTest, OverseerModifyCollectionTest, URLClassifyProcessorTest, TestDistribDocBasedVersion, NotRequiredUniqueKeyTest, AliasIntegrationTest, OverseerTest, SolrTestCaseJ4Test, ClusterStateTest, CursorMarkTest, RecoveryZkTest, TestManagedSchemaAPI, AtomicUpdatesTest, TestImpersonationWithHadoopAuth, DeleteNodeTest, MultiTermTest, TestSimpleQParserPlugin, TestManagedSynonymGraphFilterFactory, TestUtils, TestSolrDeletionPolicy2, TermVectorComponentDistributedTest, TestAnalyzeInfixSuggestions, TestSurroundQueryParser, SecurityConfHandlerTest, TestRecoveryHdfs, TestStressUserVersions, TestRuleBasedAuthorizationPlugin, CheckHdfsIndexTest, TestInPlaceUpdatesDistrib, TestIndexSearcher, BJQParserTest, ShufflingReplicaListTransformerTest, TestShardHandlerFactory, BadIndexSchemaTest, TestSlowCompositeReaderWrapper, TestSolrCoreProperties, CollectionReloadTest, TestAnalyzedSuggestions, TestUseDocValuesAsStored2, UnloadDistributedZkTest, MoveReplicaTest, TestSystemCollAutoCreate, TestCopyFieldCollectionResource, CoreAdminCreateDiscoverTest, TestQuerySenderListener, TestLeaderInitiatedRecoveryThread, StatsComponentTest, TestMacros, DocumentBuilderTest, TestTrie, TestReversedWildcardFilterFactory, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, SyncSliceTest, FullSolrCloudDistribCmdsTest, ZkSolrClientTest, TestZkChroot, TestRandomDVFaceting, ShardRoutingCustomTest, TestDistributedSearch]
[junit4] Completed [290/727 (1!)] on J2 in 1.57s, 1 test, 1 failure <<< FAILURES!
[...truncated 37938 lines...]
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk1.8.0_141) - Build #
20245 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20245/
Java: 64bit/jdk1.8.0_141 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.TestDistributedSearch.test
Error Message:
.responseHeader.status!=warnings (unordered or missing)
Stack Trace:
junit.framework.AssertionFailedError: .responseHeader.status!=warnings (unordered or missing)
at __randomizedtesting.SeedInfo.seed([42901BE3AAF5531C:CAC4243904093EE4]:0)
at junit.framework.Assert.fail(Assert.java:50)
at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:913)
at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:932)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:607)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:587)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:566)
at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:241)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1011)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
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:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 11920 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> 1026161 INFO (SUITE-TestDistributedSearch-seed#[42901BE3AAF5531C]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_42901BE3AAF5531C-001/init-core-data-001
[junit4] 2> 1026161 WARN (SUITE-TestDistributedSearch-seed#[42901BE3AAF5531C]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 1026161 INFO (SUITE-TestDistributedSearch-seed#[42901BE3AAF5531C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1026162 INFO (SUITE-TestDistributedSearch-seed#[42901BE3AAF5531C]-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> 1026163 INFO (SUITE-TestDistributedSearch-seed#[42901BE3AAF5531C]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /aux/d
[junit4] 2> 1026221 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_42901BE3AAF5531C-001/tempDir-001/control/cores/collection1
[junit4] 2> 1026222 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1026223 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2fbdb025{/aux/d,null,AVAILABLE}
[junit4] 2> 1026223 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@42f9d3ac{HTTP/1.1,[http/1.1]}{127.0.0.1:33805}
[junit4] 2> 1026223 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.e.j.s.Server Started @1027721ms
[junit4] 2> 1026223 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/aux/d, hostPort=33805, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_42901BE3AAF5531C-001/tempDir-001/control/cores}
[junit4] 2> 1026223 ERROR (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1026223 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1026223 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1026223 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 1026223 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-02T12:07:08.395Z
[junit4] 2> 1026224 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_42901BE3AAF5531C-001/tempDir-001/control/solr.xml
[junit4] 2> 1026226 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@784e2bff, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1026244 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@784e2bff
[junit4] 2> 1026246 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@784e2bff
[junit4] 2> 1026246 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@784e2bff
[junit4] 2> 1026247 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_42901BE3AAF5531C-001/tempDir-001/control/cores
[junit4] 2> 1026247 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1026248 INFO (coreLoadExecutor-4928-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1026261 INFO (coreLoadExecutor-4928-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1026273 INFO (coreLoadExecutor-4928-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1026333 INFO (coreLoadExecutor-4928-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1026336 INFO (coreLoadExecutor-4928-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1026337 INFO (coreLoadExecutor-4928-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@784e2bff
[junit4] 2> 1026337 INFO (coreLoadExecutor-4928-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1026337 INFO (coreLoadExecutor-4928-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_42901BE3AAF5531C-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 1026337 INFO (coreLoadExecutor-4928-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1026359 INFO (coreLoadExecutor-4928-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1026359 INFO (coreLoadExecutor-4928-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1026360 INFO (coreLoadExecutor-4928-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1026360 INFO (coreLoadExecutor-4928-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1026360 INFO (coreLoadExecutor-4928-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=18.7939453125, floorSegmentMB=2.1142578125, forceMergeDeletesPctAllowed=4.359767474186496, segmentsPerTier=6.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.35883407828469893
[junit4] 2> 1026361 INFO (coreLoadExecutor-4928-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@72e4eae7[collection1] main]
[junit4] 2> 1026361 INFO (coreLoadExecutor-4928-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1026373 INFO (coreLoadExecutor-4928-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1026423 INFO (coreLoadExecutor-4928-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1026423 INFO (searcherExecutor-4929-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1026423 INFO (coreLoadExecutor-4928-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1574621023929630720
[junit4] 2> 1026423 INFO (searcherExecutor-4929-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1026423 INFO (searcherExecutor-4929-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1026423 INFO (searcherExecutor-4929-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1026423 INFO (searcherExecutor-4929-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1026424 INFO (searcherExecutor-4929-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1026424 INFO (searcherExecutor-4929-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1026424 INFO (searcherExecutor-4929-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1026424 INFO (searcherExecutor-4929-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1026425 INFO (searcherExecutor-4929-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@72e4eae7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1026810 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_42901BE3AAF5531C-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 1026811 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1026812 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5b99ef59{/aux/d,null,AVAILABLE}
[junit4] 2> 1026812 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@46bef82d{HTTP/1.1,[http/1.1]}{127.0.0.1:40695}
[junit4] 2> 1026812 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.e.j.s.Server Started @1028311ms
[junit4] 2> 1026812 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/aux/d, hostPort=40695, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_42901BE3AAF5531C-001/tempDir-001/shard0/cores}
[junit4] 2> 1026812 ERROR (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1026812 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1026812 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1026812 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 1026812 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-02T12:07:08.984Z
[junit4] 2> 1026813 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_42901BE3AAF5531C-001/tempDir-001/shard0/solr.xml
[junit4] 2> 1026815 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@784e2bff, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1026829 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@784e2bff
[junit4] 2> 1026832 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@784e2bff
[junit4] 2> 1026832 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@784e2bff
[junit4] 2> 1026833 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_42901BE3AAF5531C-001/tempDir-001/shard0/cores
[junit4] 2> 1026833 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1026833 INFO (coreLoadExecutor-4939-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1026853 INFO (coreLoadExecutor-4939-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1026864 INFO (coreLoadExecutor-4939-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1026916 INFO (coreLoadExecutor-4939-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1026919 INFO (coreLoadExecutor-4939-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1026920 INFO (coreLoadExecutor-4939-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@784e2bff
[junit4] 2> 1026920 INFO (coreLoadExecutor-4939-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1026920 INFO (coreLoadExecutor-4939-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_42901BE3AAF5531C-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 1026921 INFO (coreLoadExecutor-4939-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1026945 INFO (coreLoadExecutor-4939-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1026945 INFO (coreLoadExecutor-4939-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1026945 INFO (coreLoadExecutor-4939-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1026945 INFO (coreLoadExecutor-4939-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1026946 INFO (coreLoadExecutor-4939-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=18.7939453125, floorSegmentMB=2.1142578125, forceMergeDeletesPctAllowed=4.359767474186496, segmentsPerTier=6.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.35883407828469893
[junit4] 2> 1026946 INFO (coreLoadExecutor-4939-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@36a74cb1[collection1] main]
[junit4] 2> 1026946 INFO (coreLoadExecutor-4939-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1026958 INFO (coreLoadExecutor-4939-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1027008 INFO (coreLoadExecutor-4939-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1027008 INFO (searcherExecutor-4940-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1027008 INFO (coreLoadExecutor-4939-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1574621024543047680
[junit4] 2> 1027008 INFO (searcherExecutor-4940-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1027008 INFO (searcherExecutor-4940-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1027008 INFO (searcherExecutor-4940-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1027008 INFO (searcherExecutor-4940-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1027008 INFO (searcherExecutor-4940-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1027009 INFO (searcherExecutor-4940-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1027009 INFO (searcherExecutor-4940-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1027009 INFO (searcherExecutor-4940-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1027010 INFO (searcherExecutor-4940-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@36a74cb1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1027350 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1027353 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1574621024902709248)} 0 1
[junit4] 2> 1027355 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1574621024905854976)} 0 1
[junit4] 2> 1027357 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[1 (1574621024907952128)]} 0 1
[junit4] 2> 1027359 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[1 (1574621024910049280)]} 0 0
[junit4] 2> 1027359 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[2 (1574621024911097856)]} 0 0
[junit4] 2> 1027360 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[2 (1574621024912146432)]} 0 0
[junit4] 2> 1027361 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[3 (1574621024913195008)]} 0 0
[junit4] 2> 1027362 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[3 (1574621024913195008)]} 0 0
[junit4] 2> 1027363 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[4 (1574621024914243584)]} 0 0
[junit4] 2> 1027363 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[4 (1574621024915292160)]} 0 0
[junit4] 2> 1027364 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[5 (1574621024916340736)]} 0 0
[junit4] 2> 1027365 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[5 (1574621024916340736)]} 0 0
[junit4] 2> 1027365 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[6 (1574621024917389312)]} 0 0
[junit4] 2> 1027366 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[6 (1574621024918437888)]} 0 0
[junit4] 2> 1027367 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[7 (1574621024919486464)]} 0 0
[junit4] 2> 1027367 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[7 (1574621024919486464)]} 0 0
[junit4] 2> 1027368 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[8 (1574621024920535040)]} 0 0
[junit4] 2> 1027369 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[8 (1574621024921583616)]} 0 0
[junit4] 2> 1027370 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[9 (1574621024921583616)]} 0 0
[junit4] 2> 1027370 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[9 (1574621024922632192)]} 0 0
[junit4] 2> 1027371 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574621024923680768,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1027371 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@626667f8 commitCommandVersion:1574621024923680768
[junit4] 2> 1027385 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5cb82f4e[collection1] main]
[junit4] 2> 1027385 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1027387 INFO (searcherExecutor-4929-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5cb82f4e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9)))}
[junit4] 2> 1027387 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 16
[junit4] 2> 1027388 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574621024941506560,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1027388 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4ffca9bf commitCommandVersion:1574621024941506560
[junit4] 2> 1027395 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2cac5b6c[collection1] main]
[junit4] 2> 1027396 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1027398 INFO (searcherExecutor-4940-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2cac5b6c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9)))}
[junit4] 2> 1027398 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 10
[junit4] 2> 1027401 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[10 (1574621024953040896)]} 0 2
[junit4] 2> 1027403 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[10 (1574621024955138048)]} 0 2
[junit4] 2> 1027404 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[11 (1574621024958283776)]} 0 0
[junit4] 2> 1027405 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[11 (1574621024959332352)]} 0 0
[junit4] 2> 1027406 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[12 (1574621024960380928)]} 0 0
[junit4] 2> 1027406 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[12 (1574621024960380928)]} 0 0
[junit4] 2> 1027407 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[13 (1574621024961429504)]} 0 0
[junit4] 2> 1027408 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[13 (1574621024962478080)]} 0 0
[junit4] 2> 1027409 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[1001 (1574621024962478080)]} 0 0
[junit4] 2> 1027410 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[1001 (1574621024963526656)]} 0 0
[junit4] 2> 1027411 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[14 (1574621024964575232)]} 0 0
[junit4] 2> 1027411 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[14 (1574621024965623808)]} 0 0
[junit4] 2> 1027412 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[15 (1574621024966672384)]} 0 0
[junit4] 2> 1027412 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[15 (1574621024966672384)]} 0 0
[junit4] 2> 1027413 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[16 (1574621024967720960)]} 0 0
[junit4] 2> 1027414 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[16 (1574621024968769536)]} 0 0
[junit4] 2> 1027415 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[17 (1574621024968769536)]} 0 0
[junit4] 2> 1027415 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[17 (1574621024969818112)]} 0 0
[junit4] 2> 1027416 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[100 (1574621024970866688)]} 0 0
[junit4] 2> 1027417 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[100 (1574621024971915264)]} 0 0
[junit4] 2> 1027417 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[101 (1574621024971915264)]} 0 0
[junit4] 2> 1027418 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[101 (1574621024972963840)]} 0 0
[junit4] 2> 1027419 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[102 (1574621024972963840)]} 0 0
[junit4] 2> 1027419 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[102 (1574621024974012416)]} 0 0
[junit4] 2> 1027420 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[103 (1574621024975060992)]} 0 0
[junit4] 2> 1027421 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[103 (1574621024975060992)]} 0 0
[junit4] 2> 1027421 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[104 (1574621024976109568)]} 0 0
[junit4] 2> 1027422 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[104 (1574621024977158144)]} 0 0
[junit4] 2> 1027422 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[105 (1574621024977158144)]} 0 0
[junit4] 2> 1027423 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[105 (1574621024978206720)]} 0 0
[junit4] 2> 1027424 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[106 (1574621024979255296)]} 0 0
[junit4] 2> 1027424 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[106 (1574621024979255296)]} 0 0
[junit4] 2> 1027425 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[107 (1574621024980303872)]} 0 0
[junit4] 2> 1027426 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[107 (1574621024980303872)]} 0 0
[junit4] 2> 1027426 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[108 (1574621024981352448)]} 0 0
[junit4] 2> 1027427 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[108 (1574621024982401024)]} 0 0
[junit4] 2> 1027428 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[109 (1574621024982401024)]} 0 0
[junit4] 2> 1027428 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[109 (1574621024983449600)]} 0 0
[junit4] 2> 1027429 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[110 (1574621024984498176)]} 0 0
[junit4] 2> 1027429 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[110 (1574621024984498176)]} 0 0
[junit4] 2> 1027430 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[111 (1574621024985546752)]} 0 0
[junit4] 2> 1027431 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[111 (1574621024985546752)]} 0 0
[junit4] 2> 1027431 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[112 (1574621024986595328)]} 0 0
[junit4] 2> 1027432 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[112 (1574621024987643904)]} 0 0
[junit4] 2> 1027432 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[113 (1574621024987643904)]} 0 0
[junit4] 2> 1027433 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[113 (1574621024988692480)]} 0 0
[junit4] 2> 1027434 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[114 (1574621024988692480)]} 0 0
[junit4] 2> 1027434 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[114 (1574621024989741056)]} 0 0
[junit4] 2> 1027434 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[115 (1574621024989741056)]} 0 0
[junit4] 2> 1027435 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[115 (1574621024990789632)]} 0 0
[junit4] 2> 1027435 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[116 (1574621024990789632)]} 0 0
[junit4] 2> 1027436 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[116 (1574621024991838208)]} 0 0
[junit4] 2> 1027436 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[117 (1574621024991838208)]} 0 0
[junit4] 2> 1027437 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[117 (1574621024992886784)]} 0 0
[junit4] 2> 1027437 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[118 (1574621024992886784)]} 0 0
[junit4] 2> 1027438 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[118 (1574621024993935360)]} 0 0
[junit4] 2> 1027438 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[119 (1574621024993935360)]} 0 0
[junit4] 2> 1027438 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[119 (1574621024993935361)]} 0 0
[junit4] 2> 1027439 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[120 (1574621024994983936)]} 0 0
[junit4] 2> 1027439 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[120 (1574621024994983936)]} 0 0
[junit4] 2> 1027440 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[121 (1574621024996032512)]} 0 0
[junit4] 2> 1027440 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[121 (1574621024996032512)]} 0 0
[junit4] 2> 1027441 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[122 (1574621024996032513)]} 0 0
[junit4] 2> 1027441 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[122 (1574621024997081088)]} 0 0
[junit4] 2> 1027441 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[123 (1574621024997081088)]} 0 0
[junit4] 2> 1027442 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[123 (1574621024998129664)]} 0 0
[junit4] 2> 1027442 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[124 (1574621024998129664)]} 0 0
[junit4] 2> 1027443 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[124 (1574621024998129665)]} 0 0
[junit4] 2> 1027443 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[125 (1574621024999178240)]} 0 0
[junit4] 2> 1027443 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[125 (1574621024999178240)]} 0 0
[junit4] 2> 1027444 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[126 (1574621024999178241)]} 0 0
[junit4] 2> 1027444 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[126 (1574621025000226816)]} 0 0
[junit4] 2> 1027444 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[127 (1574621025000226816)]} 0 0
[junit4] 2> 1027445 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[127 (1574621025000226817)]} 0 0
[junit4] 2> 1027445 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[128 (1574621025001275392)]} 0 0
[junit4] 2> 1027445 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[128 (1574621025001275392)]} 0 0
[junit4] 2> 1027446 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[129 (1574621025002323968)]} 0 0
[junit4] 2> 1027446 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[129 (1574621025002323968)]} 0 0
[junit4] 2> 1027446 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[130 (1574621025002323969)]} 0 0
[junit4] 2> 1027447 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[130 (1574621025003372544)]} 0 0
[junit4] 2> 1027447 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[131 (1574621025003372544)]} 0 0
[junit4] 2> 1027448 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[131 (1574621025003372545)]} 0 0
[junit4] 2> 1027448 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[132 (1574621025004421120)]} 0 0
[junit4] 2> 1027448 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[132 (1574621025004421120)]} 0 0
[junit4] 2> 1027448 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[133 (1574621025004421121)]} 0 0
[junit4] 2> 1027449 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[133 (1574621025005469696)]} 0 0
[junit4] 2> 1027449 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[134 (1574621025005469696)]} 0 0
[junit4] 2> 1027449 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[134 (1574621025005469697)]} 0 0
[junit4] 2> 1027450 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[135 (1574621025005469697)]} 0 0
[junit4] 2> 1027450 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[135 (1574621025006518272)]} 0 0
[junit4] 2> 1027450 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[136 (1574621025006518272)]} 0 0
[junit4] 2> 1027450 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[136 (1574621025006518273)]} 0 0
[junit4] 2> 1027451 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[137 (1574621025007566848)]} 0 0
[junit4] 2> 1027451 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[137 (1574621025007566848)]} 0 0
[junit4] 2> 1027451 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[138 (1574621025007566849)]} 0 0
[junit4] 2> 1027452 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[138 (1574621025008615424)]} 0 0
[junit4] 2> 1027452 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[139 (1574621025008615424)]} 0 0
[junit4] 2> 1027452 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[139 (1574621025008615425)]} 0 0
[junit4] 2> 1027452 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[140 (1574621025008615425)]} 0 0
[junit4] 2> 1027453 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[140 (1574621025009664000)]} 0 0
[junit4] 2> 1027453 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[141 (1574621025009664000)]} 0 0
[junit4] 2> 1027453 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[141 (1574621025009664001)]} 0 0
[junit4] 2> 1027453 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[142 (1574621025009664001)]} 0 0
[junit4] 2> 1027454 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[142 (1574621025010712576)]} 0 0
[junit4] 2> 1027454 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[143 (1574621025010712576)]} 0 0
[junit4] 2> 1027454 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[143 (1574621025010712577)]} 0 0
[junit4] 2> 1027454 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[144 (1574621025010712577)]} 0 0
[junit4] 2> 1027455 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[144 (1574621025010712578)]} 0 0
[junit4] 2> 1027455 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[145 (1574621025011761152)]} 0 0
[junit4] 2> 1027455 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[145 (1574621025011761152)]} 0 0
[junit4] 2> 1027455 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[146 (1574621025011761153)]} 0 0
[junit4] 2> 1027455 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[146 (1574621025011761153)]} 0 0
[junit4] 2> 1027456 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[147 (1574621025012809728)]} 0 0
[junit4] 2> 1027456 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[147 (1574621025012809728)]} 0 0
[junit4] 2> 1027456 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[148 (1574621025012809729)]} 0 0
[junit4] 2> 1027456 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[148 (1574621025012809729)]} 0 0
[junit4] 2> 1027457 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[149 (1574621025012809730)]} 0 0
[junit4] 2> 1027457 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={wt=javabin&version=2}{add=[149 (1574621025013858304)]} 0 0
[junit4] 2> 1027457 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574621025013858304,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1027457 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@626667f8 commitCommandVersion:1574621025013858304
[junit4] 2> 1027461 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3cdab6f7[collection1] main]
[junit4] 2> 1027462 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1027463 INFO (searcherExecutor-4929-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3cdab6f7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9) Uninverting(_1(8.0.0):c59)))}
[junit4] 2> 1027463 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 5
[junit4] 2> 1027463 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1574621025020149760,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1027463 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4ffca9bf commitCommandVersion:1574621025020149760
[junit4] 2> 1027467 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@298c79d5[collection1] main]
[junit4] 2> 1027468 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1027469 INFO (searcherExecutor-4940-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@298c79d5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9) Uninverting(_1(8.0.0):c59)))}
[junit4] 2> 1027469 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/aux/d path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 5
[junit4] 2> 1027469 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 1027469 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 1027470 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 1027470 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 1027471 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027485 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::213]:33332/aux/d|[ff01::083]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1|[ff01::114]:33332/aux/d&rows=10&version=2&q=*:*&NOW=1501675629644&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027487 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629644&ids=121,11,133,112,135,146,6,1001,107,108&isShard=true&shard.url=[ff01::213]:33332/aux/d|[ff01::083]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1|[ff01::114]:33332/aux/d&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027487 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=[ff01::213]:33332/aux/d|[ff01::083]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1|[ff01::114]:33332/aux/d&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=15
[junit4] 2> 1027488 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027496 INFO (qtp2088426305-17453) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::213]:33332/aux/d|[ff01::114]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&rows=10&version=2&q=*:*&NOW=1501675629661&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027497 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629661&ids=1,3,124,136,114,127,7,106,118,142&isShard=true&shard.url=[ff01::213]:33332/aux/d|[ff01::114]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027498 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=[ff01::213]:33332/aux/d|[ff01::114]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=8
[junit4] 2> 1027499 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027500 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:40695/aux/d/collection1|[ff01::114]:33332/aux/d&rows=10&version=2&q=*:*&NOW=1501675629671&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027501 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629671&ids=110,1,144,113,135,14,3,4,138,128&isShard=true&shard.url=http://127.0.0.1:40695/aux/d/collection1|[ff01::114]:33332/aux/d&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027501 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=http://127.0.0.1:40695/aux/d/collection1|[ff01::114]:33332/aux/d&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1027502 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027503 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d 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:40695/aux/d/collection1&rows=10&version=2&q=*:*&NOW=1501675629675&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027504 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629675&ids=11,133,124,136,116,105,118,8,130,141&isShard=true&shard.url=http://127.0.0.1:40695/aux/d/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027504 INFO (qtp2088426305-17453) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=http://127.0.0.1:40695/aux/d/collection1&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1027505 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027506 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1|[ff01::114]:33332/aux/d|[ff01::083]:33332/aux/d&rows=10&version=2&q=*:*&NOW=1501675629678&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027507 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629678&ids=143,101,14,147,15,5,137,119,140,120&isShard=true&shard.url=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1|[ff01::114]:33332/aux/d|[ff01::083]:33332/aux/d&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027508 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1|[ff01::114]:33332/aux/d|[ff01::083]:33332/aux/d&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1027508 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027509 INFO (qtp2088426305-17453) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&rows=10&version=2&q=*:*&NOW=1501675629681&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027510 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629681&ids=3,113,103,136,4,126,116,17,8,10&isShard=true&shard.url=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027510 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1027511 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027512 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&rows=10&version=2&q=*:*&NOW=1501675629684&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027513 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629684&ids=143,11,133,146,14,125,137,105,128,1001&isShard=true&shard.url=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027514 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1027515 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027516 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:40695/aux/d/collection1|[ff01::114]:33332/aux/d|[ff01::213]:33332/aux/d&rows=10&version=2&q=*:*&NOW=1501675629687&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027517 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629687&ids=110,12,13,145,113,6,7,106,8,129&isShard=true&shard.url=http://127.0.0.1:40695/aux/d/collection1|[ff01::114]:33332/aux/d|[ff01::213]:33332/aux/d&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027517 INFO (qtp2088426305-17453) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=http://127.0.0.1:40695/aux/d/collection1|[ff01::114]:33332/aux/d|[ff01::213]:33332/aux/d&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1027518 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027519 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=[ff01::114]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1|[ff01::083]:33332/aux/d|[ff01::213]:33332/aux/d&rows=10&version=2&q=*:*&NOW=1501675629691&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027520 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629691&ids=132,122,133,146,104,17,106,107,130,120&isShard=true&shard.url=[ff01::114]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1|[ff01::083]:33332/aux/d|[ff01::213]:33332/aux/d&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027521 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=[ff01::114]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1|[ff01::083]:33332/aux/d|[ff01::213]:33332/aux/d&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 1027522 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027523 INFO (qtp2088426305-17453) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&rows=10&version=2&q=*:*&NOW=1501675629695&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027524 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629695&ids=143,121,12,100,101,3,103,149,140,141&isShard=true&shard.url=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027525 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 1027525 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027526 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=[ff01::083]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1|[ff01::213]:33332/aux/d&rows=10&version=2&q=*:*&NOW=1501675629698&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027527 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629698&ids=135,14,137,126,115,149,128,139,107,120&isShard=true&shard.url=[ff01::083]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1|[ff01::213]:33332/aux/d&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027528 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=[ff01::083]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1|[ff01::213]:33332/aux/d&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 1027529 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027530 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=http://127.0.0.1:40695/aux/d/collection1|[ff01::083]:33332/aux/d|[ff01::213]:33332/aux/d&rows=10&version=2&q=*:*&NOW=1501675629701&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027530 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629701&ids=122,112,2,103,104,116,6,17,1001,129&isShard=true&shard.url=http://127.0.0.1:40695/aux/d/collection1|[ff01::083]:33332/aux/d|[ff01::213]:33332/aux/d&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027531 INFO (qtp2088426305-17453) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=http://127.0.0.1:40695/aux/d/collection1|[ff01::083]:33332/aux/d|[ff01::213]:33332/aux/d&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1027532 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027533 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&rows=10&version=2&q=*:*&NOW=1501675629704&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027533 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629704&ids=100,12,145,14,102,114,16,6,118,142&isShard=true&shard.url=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027534 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1027534 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027535 INFO (qtp2088426305-17453) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=http://127.0.0.1:40695/aux/d/collection1|[ff01::213]:33332/aux/d&rows=10&version=2&q=*:*&NOW=1501675629707&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027536 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629707&ids=121,112,3,113,137,116,17,7,9,141&isShard=true&shard.url=http://127.0.0.1:40695/aux/d/collection1|[ff01::213]:33332/aux/d&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027537 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=http://127.0.0.1:40695/aux/d/collection1|[ff01::213]:33332/aux/d&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1027537 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027538 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1|[ff01::114]:33332/aux/d|[ff01::083]:33332/aux/d&rows=10&version=2&q=*:*&NOW=1501675629710&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027539 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629710&ids=143,132,144,14,15,114,105,138,106,131&isShard=true&shard.url=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1|[ff01::114]:33332/aux/d|[ff01::083]:33332/aux/d&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027539 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1|[ff01::114]:33332/aux/d|[ff01::083]:33332/aux/d&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1027540 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027541 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=http://127.0.0.1:40695/aux/d/collection1&rows=10&version=2&q=*:*&NOW=1501675629713&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027542 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629713&ids=122,100,145,134,2,136,5,116,7,119&isShard=true&shard.url=http://127.0.0.1:40695/aux/d/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027542 INFO (qtp2088426305-17453) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=http://127.0.0.1:40695/aux/d/collection1&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1027543 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027544 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=[ff01::114]:33332/aux/d|[ff01::083]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1|[ff01::213]:33332/aux/d&rows=10&version=2&q=*:*&NOW=1501675629715&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027545 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629715&ids=11,132,133,1,134,124,116,17,117,108&isShard=true&shard.url=[ff01::114]:33332/aux/d|[ff01::083]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1|[ff01::213]:33332/aux/d&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027545 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=[ff01::114]:33332/aux/d|[ff01::083]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1|[ff01::213]:33332/aux/d&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1027546 INFO (qtp1423106060-17442) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027547 INFO (qtp2088426305-17453) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+asc&shard.url=http://127.0.0.1:40695/aux/d/collection1|[ff01::083]:33332/aux/d&rows=10&version=2&q=*:*&NOW=1501675629719&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027547 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629719&ids=144,145,123,4,136,1001,106,141,130,10&isShard=true&shard.url=http://127.0.0.1:40695/aux/d/collection1|[ff01::083]:33332/aux/d&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027548 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=http://127.0.0.1:40695/aux/d/collection1|[ff01::083]:33332/aux/d&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1027549 INFO (qtp1423106060-17435) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort=a_i_p+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027550 INFO (qtp2088426305-17452) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i_p+desc&shard.url=[ff01::114]:33332/aux/d|[ff01::083]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&rows=10&version=2&q=*:*&NOW=1501675629721&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027551 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629721&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::114]:33332/aux/d|[ff01::083]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027551 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=[ff01::114]:33332/aux/d|[ff01::083]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&sort=a_i_p+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1027552 INFO (qtp1423106060-17440) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i_p,5))+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1027553 INFO (qtp2088426305-17457) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort={!func}testfunc(add(a_i_p,5))+desc&shard.url=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&rows=10&version=2&q=*:*&NOW=1501675629725&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1027554 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501675629725&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1027554 INFO (qtp2088426305-17453) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=*:*&shards=[ff01::213]:33332/aux/d|http://127.0.0.1:40695/aux/d/collection1&sort={!func}testfunc(add(a_i_p,5))+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1027555 INFO (qtp1423106060-17441) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=a_i_p[*+TO+*]&distrib=false&sort=a_i_p+asc&wt=javabin&version=2} hits=13 status=0 QTime=0
[junit4] 2> 1027557 INFO (qtp2088426305-17458) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i_p+asc&shard.url=http://127.0.0.1:40695/aux/d/collection1|[ff01::213]:33332/aux/d|[ff01::114]:33332/aux/d|[ff01::083]:33332/aux/d&rows=10&version=2&q=a_i_p[*+TO+*]&NOW=1501675629728&isShard=true&wt=javabin} hits=13 status=0 QTime=0
[junit4] 2> 1027557 INFO (qtp2088426305-17459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/aux/d path=/select params={q=a_i_p[*+TO+*]&df=text&distrib=false&shards.purpose=64&NOW=1501675629728&ids=11,12,1,13,2
[...truncated too long message...]
tainer Shutting down CoreContainer instance=1623054781
[junit4] 2> 1027618 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1027620 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1027621 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1027621 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1027621 INFO (coreCloseExecutor-4945-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@50576bb5
[junit4] 2> 1027621 INFO (coreCloseExecutor-4945-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1347906485
[junit4] 2> 1027624 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2fbdb025{/aux/d,null,UNAVAILABLE}
[junit4] 2> 1027625 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@46bef82d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1027625 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1621989391
[junit4] 2> 1027625 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1027625 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1027625 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1027625 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1027625 INFO (coreCloseExecutor-4947-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@51ff816f
[junit4] 2> 1027626 INFO (coreCloseExecutor-4947-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1375699311
[junit4] 2> 1027626 INFO (TEST-TestDistributedSearch.test-seed#[42901BE3AAF5531C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5b99ef59{/aux/d,null,UNAVAILABLE}
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=42901BE3AAF5531C -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sv -Dtests.timezone=America/Regina -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 1.47s J1 | TestDistributedSearch.test <<<
[junit4] > Throwable #1: junit.framework.AssertionFailedError: .responseHeader.status!=warnings (unordered or missing)
[junit4] > at __randomizedtesting.SeedInfo.seed([42901BE3AAF5531C:CAC4243904093EE4]:0)
[junit4] > at junit.framework.Assert.fail(Assert.java:50)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:913)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:932)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:607)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:587)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:566)
[junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:241)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1011)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_42901BE3AAF5531C-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {rnd_b=PostingsFormat(name=LuceneVarGapDocFreqInterval), a_s=PostingsFormat(name=MockRandom), foo_sev_enum=PostingsFormat(name=MockRandom), SubjectTerms_mfacet=PostingsFormat(name=LuceneVarGapFixedInterval), oddField_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), multiDefault=FSTOrd50, lowerfilt1and2=FSTOrd50, a_t=FSTOrd50, foo_b=PostingsFormat(name=MockRandom), lowerfilt=FSTOrd50, id=PostingsFormat(name=LuceneVarGapDocFreqInterval), text=PostingsFormat(name=MockRandom)}, docValues:{other_tl1=DocValuesFormat(name=Lucene70), range_facet_l_dv=DocValuesFormat(name=Lucene70), foo_sev_enum=DocValuesFormat(name=Direct), n_l1=DocValuesFormat(name=Memory), a_n_tdt=DocValuesFormat(name=Direct), intDefault=DocValuesFormat(name=Lucene70), n_dt1=DocValuesFormat(name=Lucene70), n_td1=DocValuesFormat(name=Lucene70), b_n_tdt=DocValuesFormat(name=Lucene70), n_d1=DocValuesFormat(name=Memory), range_facet_l=DocValuesFormat(name=Memory), n_f1=DocValuesFormat(name=Lucene70), n_ti1=DocValuesFormat(name=Lucene70), n_tl1=DocValuesFormat(name=Lucene70), _version_=DocValuesFormat(name=Lucene70), n_tf1=DocValuesFormat(name=Memory), n_tdt1=DocValuesFormat(name=Lucene70), id_i1=DocValuesFormat(name=Lucene70), foo_d=DocValuesFormat(name=Lucene70), range_facet_i_dv=DocValuesFormat(name=Memory), foo_f=DocValuesFormat(name=Direct), intDvoDefault=DocValuesFormat(name=Lucene70), timestamp=DocValuesFormat(name=Memory), a_i_p=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=576, maxMBSortInHeap=5.584296358800282, sim=RandomSimilarity(queryNorm=false): {}, locale=sv, timezone=America/Regina
[junit4] 2> NOTE: Linux 4.10.0-27-generic amd64/Oracle Corporation 1.8.0_141 (64-bit)/cpus=8,threads=1,free=184301192,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [ClassificationUpdateProcessorIntegrationTest, BlobRepositoryCloudTest, TestHashPartitioner, DisMaxRequestHandlerTest, TestSweetSpotSimilarityFactory, TestLegacyFieldReuse, UtilsToolTest, AtomicUpdateProcessorFactoryTest, StatsReloadRaceTest, ScriptEngineTest, TestPostingsSolrHighlighter, TestFilteredDocIdSet, TestExpandComponent, PrimUtilsTest, HttpPartitionTest, TestJavabinTupleStreamParser, TestSolrCoreSnapshots, OpenCloseCoreStressTest, TestScoreJoinQPScore, TestXmlQParser, SoftAutoCommitTest, TestFieldCache, TestPointFields, TestAuthenticationFramework, TestTolerantUpdateProcessorRandomCloud, TestFastLRUCache, ClusterStateUpdateTest, TestManagedResource, BooleanFieldTest, BlockCacheTest, ReplicaListTransformerTest, DistributedTermsComponentTest, LukeRequestHandlerTest, TestSQLHandler, CreateCollectionCleanupTest, SpatialRPTFieldTypeTest, AlternateDirectoryTest, TestStressVersions, CollectionsAPIDistributedZkTest, DistributedSuggestComponentTest, ActionThrottleTest, TestDistributedGrouping, SolrGraphiteReporterTest, TestSortByMinMaxFunction, TestReplicationHandlerBackup, TestQueryTypes, TestStressLiveNodes, MetricsConfigTest, LoggingHandlerTest, DOMUtilTest, TestExactStatsCache, TestFiltering, BasicAuthStandaloneTest, TestElisionMultitermQuery, ReplicationFactorTest, ConcurrentDeleteAndCreateCollectionTest, SmileWriterTest, TestUseDocValuesAsStored, DeleteLastCustomShardedReplicaTest, TestSolrIndexConfig, MigrateRouteKeyTest, SchemaVersionSpecificBehaviorTest, SparseHLLTest, SliceStateTest, TestFuzzyAnalyzedSuggestions, BigEndianAscendingWordSerializerTest, TestEmbeddedSolrServerSchemaAPI, JsonLoaderTest, TestFileDictionaryLookup, TestStandardQParsers, TestConfigSetProperties, TestOverriddenPrefixQueryForCustomFieldType, DistribDocExpirationUpdateProcessorTest, TestLegacyFieldCache, HdfsSyncSliceTest, TestSystemIdResolver, TestCursorMarkWithoutUniqueKey, DistributedMLTComponentTest, SpellCheckComponentTest, PreAnalyzedUpdateProcessorTest, CacheHeaderTest, DateRangeFieldTest, ExitableDirectoryReaderTest, MoreLikeThisHandlerTest, TestXIncludeConfig, TestConfigSetsAPIExclusivity, SolrCmdDistributorTest, DistributedFacetPivotLongTailTest, IndexSchemaRuntimeFieldTest, CSVRequestHandlerTest, CoreMergeIndexesAdminHandlerTest, DirectSolrSpellCheckerTest, DistributedExpandComponentTest, RecoveryAfterSoftCommitTest, TestMacroExpander, TestBackupRepositoryFactory, SOLR749Test, TestQueryWrapperFilter, TestClusterStateMutator, SolrIndexMetricsTest, ZkSolrClientTest, TestSizeLimitedDistributedMap, HdfsChaosMonkeySafeLeaderTest, SpatialFilterTest, ShardRoutingTest, OverseerCollectionConfigSetProcessorTest, TermVectorComponentTest, TestDelegationWithHadoopAuth, TestDynamicFieldResource, TestAtomicUpdateErrorCases, OverseerStatusTest, TestSSLRandomization, CoreAdminHandlerTest, TestPKIAuthenticationPlugin, TestPrepRecovery, TestRawTransformer, PropertiesRequestHandlerTest, BasicFunctionalityTest, TestBulkSchemaAPI, DistributedIntervalFacetingTest, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, LeaderElectionIntegrationTest, BasicZkTest, RecoveryZkTest, TestReplicationHandler, TestZkChroot, ZkCLITest, ShardRoutingCustomTest, TestDistributedSearch]
[junit4] Completed [356/727 (1!)] on J1 in 1.49s, 1 test, 1 failure <<< FAILURES!
[...truncated 44561 lines...]