Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20614/ Java: 32bit/jdk1.8.0_144 -server -XX:+UseSerialGC
2 tests failed. FAILED: org.apache.solr.TestDistributedSearch.test Error Message: Expected to find shardAddress in the up shard info: {error=org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded,trace=org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:460) at org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java:273) at org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:175) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) ,time=12} Stack Trace: java.lang.AssertionError: Expected to find shardAddress in the up shard info: {error=org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded,trace=org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:460) at org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java:273) at org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:175) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) ,time=12} at __randomizedtesting.SeedInfo.seed([E71D439256524ADD:6F497C48F8AE2725]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1191) at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1132) at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:992) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1019) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testEventQueue Error Message: action wasn't interrupted Stack Trace: java.lang.AssertionError: action wasn't interrupted at __randomizedtesting.SeedInfo.seed([E71D439256524ADD:2EA8013C5F358C28]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testEventQueue(TriggerIntegrationTest.java:683) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 11881 lines...] [junit4] Suite: org.apache.solr.TestDistributedSearch [junit4] 2> 435574 INFO (SUITE-TestDistributedSearch-seed#[E71D439256524ADD]-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/J0/temp/solr.TestDistributedSearch_E71D439256524ADD-001/init-core-data-001 [junit4] 2> 435574 WARN (SUITE-TestDistributedSearch-seed#[E71D439256524ADD]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2 [junit4] 2> 435574 INFO (SUITE-TestDistributedSearch-seed#[E71D439256524ADD]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 435576 INFO (SUITE-TestDistributedSearch-seed#[E71D439256524ADD]-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> 435576 INFO (SUITE-TestDistributedSearch-seed#[E71D439256524ADD]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 435678 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E71D439256524ADD-001/tempDir-001/control/cores/collection1 [junit4] 2> 435679 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 435686 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@77a995{/,null,AVAILABLE} [junit4] 2> 435687 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@ba2dee{HTTP/1.1,[http/1.1]}{127.0.0.1:39991} [junit4] 2> 435687 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.e.j.s.Server Started @438031ms [junit4] 2> 435687 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=39991, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E71D439256524ADD-001/tempDir-001/control/cores} [junit4] 2> 435687 ERROR (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 435687 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 435687 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 435687 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 435687 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-06T02:00:57.357Z [junit4] 2> 435687 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E71D439256524ADD-001/tempDir-001/control/solr.xml [junit4] 2> 435691 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 435691 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 435691 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@19895fb, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 435729 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19895fb [junit4] 2> 435736 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19895fb [junit4] 2> 435736 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19895fb [junit4] 2> 435737 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E71D439256524ADD-001/tempDir-001/control/cores [junit4] 2> 435737 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 435737 INFO (coreLoadExecutor-1693-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 435738 INFO (coreLoadExecutor-1693-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> 435757 INFO (coreLoadExecutor-1693-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 435778 INFO (coreLoadExecutor-1693-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 435856 INFO (coreLoadExecutor-1693-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 435861 INFO (coreLoadExecutor-1693-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> 435862 INFO (coreLoadExecutor-1693-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@19895fb [junit4] 2> 435862 INFO (coreLoadExecutor-1693-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 435862 INFO (coreLoadExecutor-1693-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/J0/temp/solr.TestDistributedSearch_E71D439256524ADD-001/tempDir-001/control/cores/collection1/data/] [junit4] 2> 435863 INFO (coreLoadExecutor-1693-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=68.849609375, floorSegmentMB=0.89453125, forceMergeDeletesPctAllowed=18.13714229814568, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 435910 INFO (coreLoadExecutor-1693-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 435910 INFO (coreLoadExecutor-1693-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 435911 INFO (coreLoadExecutor-1693-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 435911 INFO (coreLoadExecutor-1693-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 435912 INFO (coreLoadExecutor-1693-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=48.6767578125, floorSegmentMB=0.3544921875, forceMergeDeletesPctAllowed=8.933407468858027, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 435912 INFO (coreLoadExecutor-1693-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2007f7[collection1] main] [junit4] 2> 435913 INFO (coreLoadExecutor-1693-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 435933 INFO (coreLoadExecutor-1693-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 435990 INFO (coreLoadExecutor-1693-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 435990 INFO (searcherExecutor-1694-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 435990 INFO (coreLoadExecutor-1693-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1580471689056092160 [junit4] 2> 435990 INFO (searcherExecutor-1694-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 435990 INFO (searcherExecutor-1694-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 435990 INFO (searcherExecutor-1694-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 435990 INFO (searcherExecutor-1694-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 435991 INFO (searcherExecutor-1694-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 435991 INFO (searcherExecutor-1694-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 435991 INFO (searcherExecutor-1694-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 435991 INFO (searcherExecutor-1694-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 435992 INFO (searcherExecutor-1694-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2007f7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 436079 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E71D439256524ADD-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 436079 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 436080 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e90830{/,null,AVAILABLE} [junit4] 2> 436080 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@207a3c{HTTP/1.1,[http/1.1]}{127.0.0.1:39275} [junit4] 2> 436080 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.e.j.s.Server Started @438424ms [junit4] 2> 436080 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=39275, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E71D439256524ADD-001/tempDir-001/shard0/cores} [junit4] 2> 436080 ERROR (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 436080 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 436080 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 436080 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 436080 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-06T02:00:57.750Z [junit4] 2> 436081 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E71D439256524ADD-001/tempDir-001/shard0/solr.xml [junit4] 2> 436091 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 436091 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 436092 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@19895fb, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 436115 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19895fb [junit4] 2> 436121 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19895fb [junit4] 2> 436121 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19895fb [junit4] 2> 436123 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E71D439256524ADD-001/tempDir-001/shard0/cores [junit4] 2> 436123 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 436123 INFO (coreLoadExecutor-1704-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 436123 INFO (coreLoadExecutor-1704-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> 436145 INFO (coreLoadExecutor-1704-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 436167 INFO (coreLoadExecutor-1704-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 436233 INFO (coreLoadExecutor-1704-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 436238 INFO (coreLoadExecutor-1704-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> 436238 INFO (coreLoadExecutor-1704-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@19895fb [junit4] 2> 436238 INFO (coreLoadExecutor-1704-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 436238 INFO (coreLoadExecutor-1704-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/J0/temp/solr.TestDistributedSearch_E71D439256524ADD-001/tempDir-001/shard0/cores/collection1/data/] [junit4] 2> 436239 INFO (coreLoadExecutor-1704-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=68.849609375, floorSegmentMB=0.89453125, forceMergeDeletesPctAllowed=18.13714229814568, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 436269 INFO (coreLoadExecutor-1704-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 436269 INFO (coreLoadExecutor-1704-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 436269 INFO (coreLoadExecutor-1704-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 436269 INFO (coreLoadExecutor-1704-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 436270 INFO (coreLoadExecutor-1704-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=48.6767578125, floorSegmentMB=0.3544921875, forceMergeDeletesPctAllowed=8.933407468858027, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 436270 INFO (coreLoadExecutor-1704-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1dd29c2[collection1] main] [junit4] 2> 436271 INFO (coreLoadExecutor-1704-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 436286 INFO (coreLoadExecutor-1704-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 436343 INFO (coreLoadExecutor-1704-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 436343 INFO (searcherExecutor-1705-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 436343 INFO (coreLoadExecutor-1704-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1580471689426239488 [junit4] 2> 436344 INFO (searcherExecutor-1705-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 436344 INFO (searcherExecutor-1705-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 436344 INFO (searcherExecutor-1705-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 436344 INFO (searcherExecutor-1705-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 436344 INFO (searcherExecutor-1705-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 436344 INFO (searcherExecutor-1705-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 436344 INFO (searcherExecutor-1705-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 436344 INFO (searcherExecutor-1705-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 436345 INFO (searcherExecutor-1705-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1dd29c2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 436346 INFO (TEST-TestDistributedSearch.test-seed#[E71D439256524ADD]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 436348 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1580471689430433792)} 0 1 [junit4] 2> 436350 INFO (qtp22104361-3663) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1580471689432530944)} 0 1 [junit4] 2> 436356 INFO (qtp24609930-3647) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1580471689434628096)]} 0 4 [junit4] 2> 436357 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1580471689439870976)]} 0 1 [junit4] 2> 436359 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1580471689441968128)]} 0 1 [junit4] 2> 436360 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1580471689443016704)]} 0 0 [junit4] 2> 436361 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1580471689444065280)]} 0 0 [junit4] 2> 436362 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1580471689445113856)]} 0 0 [junit4] 2> 436374 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1580471689446162432)]} 0 11 [junit4] 2> 436375 INFO (qtp22104361-3663) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1580471689458745344)]} 0 0 [junit4] 2> 436376 INFO (qtp24609930-3647) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1580471689459793920)]} 0 0 [junit4] 2> 436377 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1580471689460842496)]} 0 0 [junit4] 2> 436377 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1580471689461891072)]} 0 0 [junit4] 2> 436378 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1580471689462939648)]} 0 0 [junit4] 2> 436379 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1580471689462939648)]} 0 0 [junit4] 2> 436380 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1580471689463988224)]} 0 0 [junit4] 2> 436381 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1580471689465036800)]} 0 0 [junit4] 2> 436381 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1580471689466085376)]} 0 0 [junit4] 2> 436382 INFO (qtp24609930-3647) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1580471689467133952)]} 0 0 [junit4] 2> 436383 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1580471689467133952)]} 0 0 [junit4] 2> 436383 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1580471689468182528,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 436383 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3428a1 commitCommandVersion:1580471689468182528 [junit4] 2> 436394 INFO (qtp24609930-3641) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@a1b49a[collection1] main] [junit4] 2> 436394 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 436396 INFO (searcherExecutor-1694-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@a1b49a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C9)))} [junit4] 2> 436396 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 13 [junit4] 2> 436396 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1580471689481814016,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 436396 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@9cd902 commitCommandVersion:1580471689481814016 [junit4] 2> 436401 INFO (qtp22104361-3664) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c819cd[collection1] main] [junit4] 2> 436401 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 436403 INFO (searcherExecutor-1705-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c819cd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C9)))} [junit4] 2> 436403 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 6 [junit4] 2> 436405 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1580471689489154048)]} 0 1 [junit4] 2> 436407 INFO (qtp22104361-3663) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1580471689491251200)]} 0 1 [junit4] 2> 436408 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1580471689493348352)]} 0 0 [junit4] 2> 436408 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1580471689494396928)]} 0 0 [junit4] 2> 436409 INFO (qtp24609930-3647) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1580471689495445504)]} 0 0 [junit4] 2> 436410 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1580471689495445504)]} 0 0 [junit4] 2> 436410 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1580471689496494080)]} 0 0 [junit4] 2> 436411 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1580471689497542656)]} 0 0 [junit4] 2> 436412 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1580471689497542656)]} 0 0 [junit4] 2> 436413 INFO (qtp22104361-3663) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1580471689498591232)]} 0 0 [junit4] 2> 436413 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1580471689499639808)]} 0 0 [junit4] 2> 436414 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1580471689500688384)]} 0 0 [junit4] 2> 436415 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1580471689500688384)]} 0 0 [junit4] 2> 436415 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1580471689501736960)]} 0 0 [junit4] 2> 436416 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1580471689502785536)]} 0 0 [junit4] 2> 436416 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1580471689502785536)]} 0 0 [junit4] 2> 436417 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1580471689503834112)]} 0 0 [junit4] 2> 436418 INFO (qtp22104361-3663) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1580471689504882688)]} 0 0 [junit4] 2> 436419 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1580471689504882688)]} 0 0 [junit4] 2> 436419 INFO (qtp22104361-3663) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1580471689505931264)]} 0 0 [junit4] 2> 436420 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1580471689505931264)]} 0 0 [junit4] 2> 436420 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1580471689506979840)]} 0 0 [junit4] 2> 436421 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1580471689508028416)]} 0 0 [junit4] 2> 436422 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1580471689508028416)]} 0 0 [junit4] 2> 436422 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1580471689509076992)]} 0 0 [junit4] 2> 436423 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1580471689510125568)]} 0 0 [junit4] 2> 436424 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1580471689510125568)]} 0 0 [junit4] 2> 436424 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1580471689511174144)]} 0 0 [junit4] 2> 436425 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1580471689512222720)]} 0 0 [junit4] 2> 436426 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1580471689513271296)]} 0 0 [junit4] 2> 436427 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1580471689513271296)]} 0 0 [junit4] 2> 436427 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1580471689514319872)]} 0 0 [junit4] 2> 436428 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1580471689515368448)]} 0 0 [junit4] 2> 436429 INFO (qtp22104361-3663) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1580471689515368448)]} 0 0 [junit4] 2> 436430 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1580471689516417024)]} 0 0 [junit4] 2> 436430 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1580471689517465600)]} 0 0 [junit4] 2> 436431 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1580471689517465600)]} 0 0 [junit4] 2> 436431 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1580471689518514176)]} 0 0 [junit4] 2> 436432 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1580471689518514176)]} 0 0 [junit4] 2> 436432 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1580471689519562752)]} 0 0 [junit4] 2> 436433 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1580471689519562752)]} 0 0 [junit4] 2> 436433 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1580471689520611328)]} 0 0 [junit4] 2> 436433 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1580471689520611328)]} 0 0 [junit4] 2> 436434 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1580471689521659904)]} 0 0 [junit4] 2> 436435 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1580471689521659904)]} 0 1 [junit4] 2> 436436 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1580471689523757056)]} 0 0 [junit4] 2> 436437 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1580471689523757056)]} 0 0 [junit4] 2> 436437 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1580471689524805632)]} 0 0 [junit4] 2> 436438 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1580471689525854208)]} 0 0 [junit4] 2> 436440 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1580471689526902784)]} 0 0 [junit4] 2> 436440 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1580471689527951360)]} 0 0 [junit4] 2> 436441 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1580471689528999936)]} 0 0 [junit4] 2> 436442 INFO (qtp24609930-3647) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1580471689528999936)]} 0 0 [junit4] 2> 436442 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1580471689530048512)]} 0 0 [junit4] 2> 436442 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1580471689530048512)]} 0 0 [junit4] 2> 436443 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1580471689531097088)]} 0 0 [junit4] 2> 436443 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1580471689531097088)]} 0 0 [junit4] 2> 436444 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1580471689532145664)]} 0 0 [junit4] 2> 436445 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1580471689532145664)]} 0 0 [junit4] 2> 436445 INFO (qtp22104361-3663) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1580471689533194240)]} 0 0 [junit4] 2> 436448 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1580471689535291392)]} 0 0 [junit4] 2> 436448 INFO (qtp22104361-3663) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1580471689536339968)]} 0 0 [junit4] 2> 436449 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1580471689537388544)]} 0 0 [junit4] 2> 436449 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1580471689537388544)]} 0 0 [junit4] 2> 436450 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1580471689538437120)]} 0 0 [junit4] 2> 436450 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1580471689538437120)]} 0 0 [junit4] 2> 436451 INFO (qtp24609930-3647) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1580471689539485696)]} 0 0 [junit4] 2> 436451 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1580471689539485696)]} 0 0 [junit4] 2> 436452 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1580471689539485697)]} 0 0 [junit4] 2> 436452 INFO (qtp22104361-3663) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1580471689540534272)]} 0 0 [junit4] 2> 436453 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1580471689540534272)]} 0 0 [junit4] 2> 436453 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1580471689541582848)]} 0 0 [junit4] 2> 436453 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1580471689541582848)]} 0 0 [junit4] 2> 436454 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1580471689542631424)]} 0 0 [junit4] 2> 436454 INFO (qtp24609930-3647) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1580471689542631424)]} 0 0 [junit4] 2> 436454 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1580471689542631425)]} 0 0 [junit4] 2> 436455 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1580471689543680000)]} 0 0 [junit4] 2> 436455 INFO (qtp22104361-3663) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1580471689543680000)]} 0 0 [junit4] 2> 436455 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1580471689543680001)]} 0 0 [junit4] 2> 436456 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1580471689544728576)]} 0 0 [junit4] 2> 436456 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1580471689544728576)]} 0 0 [junit4] 2> 436457 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1580471689544728577)]} 0 0 [junit4] 2> 436457 INFO (qtp24609930-3647) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1580471689545777152)]} 0 0 [junit4] 2> 436458 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1580471689545777152)]} 0 0 [junit4] 2> 436458 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1580471689546825728)]} 0 0 [junit4] 2> 436458 INFO (qtp22104361-3663) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1580471689546825728)]} 0 0 [junit4] 2> 436459 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1580471689546825729)]} 0 0 [junit4] 2> 436459 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1580471689547874304)]} 0 0 [junit4] 2> 436459 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1580471689547874304)]} 0 0 [junit4] 2> 436459 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1580471689547874305)]} 0 0 [junit4] 2> 436460 INFO (qtp24609930-3647) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1580471689548922880)]} 0 0 [junit4] 2> 436460 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1580471689548922880)]} 0 0 [junit4] 2> 436460 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1580471689548922881)]} 0 0 [junit4] 2> 436461 INFO (qtp22104361-3663) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1580471689549971456)]} 0 0 [junit4] 2> 436461 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1580471689549971456)]} 0 0 [junit4] 2> 436461 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1580471689549971457)]} 0 0 [junit4] 2> 436462 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1580471689549971457)]} 0 0 [junit4] 2> 436462 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1580471689551020032)]} 0 0 [junit4] 2> 436462 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1580471689551020032)]} 0 0 [junit4] 2> 436462 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1580471689551020033)]} 0 0 [junit4] 2> 436463 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1580471689552068608)]} 0 0 [junit4] 2> 436463 INFO (qtp22104361-3663) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1580471689552068608)]} 0 0 [junit4] 2> 436463 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1580471689552068609)]} 0 0 [junit4] 2> 436464 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1580471689552068609)]} 0 0 [junit4] 2> 436464 INFO (qtp24609930-3647) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1580471689553117184)]} 0 0 [junit4] 2> 436464 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1580471689553117184)]} 0 0 [junit4] 2> 436465 INFO (qtp24609930-3648) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1580471689553117185)]} 0 0 [junit4] 2> 436465 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1580471689554165760)]} 0 0 [junit4] 2> 436465 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1580471689554165760)]} 0 0 [junit4] 2> 436466 INFO (qtp22104361-3663) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1580471689554165761)]} 0 0 [junit4] 2> 436466 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1580471689555214336)]} 0 0 [junit4] 2> 436466 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1580471689555214336)]} 0 0 [junit4] 2> 436467 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1580471689556262912)]} 0 0 [junit4] 2> 436468 INFO (qtp22104361-3665) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1580471689556262912)]} 0 0 [junit4] 2> 436468 INFO (qtp24609930-3641) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1580471689557311488)]} 0 0 [junit4] 2> 436469 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1580471689557311488)]} 0 0 [junit4] 2> 436469 INFO (qtp24609930-3646) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1580471689558360064)]} 0 0 [junit4] 2> 436470 INFO (qtp22104361-3664) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1580471689558360064)]} 0 0 [junit4] 2> 436470 INFO (qtp24609930-3647) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1580471689559408640,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 436470 INFO (qtp24609930-3647) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3428a1 commitCommandVersion:1580471689559408640 [junit4] 2> 436480 INFO (qtp24609930-3647) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@11d107a[collection1] main] [junit4] 2> 436480 INFO (qtp24609930-3647) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 436482 INFO (searcherExecutor-1694-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@11d107a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C9) Uninverting(_1(8.0.0):C59)))} [junit4] 2> 436482 INFO (qtp24609930-3647) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 11 [junit4] 2> 436482 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1580471689571991552,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 436482 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@9cd902 commitCommandVersion:1580471689571991552 [junit4] 2> 436490 INFO (qtp22104361-3658) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@9df29[collection1] main] [junit4] 2> 436490 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 436492 INFO (searcherExecutor-1705-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@9df29[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C9) Uninverting(_1(8.0.0):C59)))} [junit4] 2> 436492 INFO (qtp22104361-3658) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 10 [junit4] 2> 436493 INFO (qtp24609930-3648) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative [junit4] 2> 436493 INFO (qtp24609930-3648) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0 [junit4] 2> 436494 INFO (qtp24609930-3641) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative [junit4] 2> 436494 INFO (qtp24609930-3641) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=0 [junit4] 2> 436494 INFO (qtp24609930-3646) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 436498 INFO (qtp22104361-3664) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:39275//collection1|[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1507255258166&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 436502 INFO (qtp22104361-3658) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1507255258166&ids=100,145,104,149,138,6,1001,9,140,141&isShard=true&shard.url=http://127.0.0.1:39275//collection1|[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 436505 INFO (qtp22104361-3665) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:39275//collection1|[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=9 [junit4] 2> 436506 INFO (qtp24609930-3647) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 436508 INFO (qtp22104361-3659) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=http://127.0.0.1:39275//collection1&rows=10&version=2&q=*:*&NOW=1507255258177&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 436510 INFO (qtp22104361-3658) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1507255258177&ids=143,122,134,124,125,137,5,128,106,142&isShard=true&shard.url=http://127.0.0.1:39275//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 436518 INFO (qtp22104361-3663) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:39275//collection1&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=10 [junit4] 2> 436519 INFO (qtp24609930-3648) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 436535 INFO (qtp22104361-3665) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:39275//collection1&rows=10&version=2&q=*:*&NOW=1507255258190&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 436536 INFO (qtp22104361-3658) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1507255258190&ids=132,112,13,2,3,103,126,117,9,141&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:39275//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 436539 INFO (qtp22104361-3664) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:39275//collection1&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=19 [junit4] 2> 436540 INFO (qtp24609930-3641) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 436541 INFO (qtp22104361-3663) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=http://127.0.0.1:39275//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1507255258211&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 436542 INFO (qtp22104361-3658) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1507255258211&ids=100,14,125,15,104,5,6,130,131,120&isShard=true&shard.url=http://127.0.0.1:39275//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 436546 INFO (qtp22104361-3659) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:39275//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5 [junit4] 2> 436547 INFO (qtp24609930-3646) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 436548 INFO (qtp22104361-3664) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:39275//collection1&rows=10&version=2&q=*:*&NOW=1507255258217&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 436549 INFO (qtp22104361-3658) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1507255258217&ids=110,122,145,135,15,136,5,127,109,130&isShard=true&shard.url=http://127.0.0.1:39275//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 436551 INFO (qtp22104361-3665) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:39275//collection1&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3 [junit4] 2> 436551 INFO (qtp24609930-3647) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 436553 INFO (qtp22104361-3659) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:39275//collection1&rows=10&version=2&q=*:*&NOW=1507255258222&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 436553 INFO (qtp22104361-3658) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1507255258222&ids=1,13,101,113,14,125,139,8,129,119&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:39275//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 436555 INFO (qtp22104361-3663) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:39275//collection1&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=2 [junit4] 2> 436556 INFO (qtp24609930-3648) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 436557 INFO (qtp22104361-3665) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=http://127.0.0.1:39275//collection1&rows=10&version=2&q=*:*&NOW=1507255258227&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 436558 INFO (qtp22104361-3658) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1507255258227&ids=132,100,145,112,101,123,136,107,9,140&isShard=true&shard.url=http://127.0.0.1:39275//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 436558 INFO (qtp22104361-3664) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:39275//collection1&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 436559 INFO (qtp24609930-3641) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 436560 INFO (qtp22104361-3663) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:39275//collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1507255258230&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 436561 INFO (qtp22104361-3658) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1507255258230&ids=121,12,146,3,104,148,6,129,118,120&isShard=true&shard.url=http://127.0.0.1:39275//collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 436562 INFO (qtp22104361-3659) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:39275//collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 436562 INFO (qtp24609930-3641) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 436563 INFO (qtp22104361-3664) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=http://127.0.0.1:39275//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1507255258233&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 436564 INFO (qtp22104361-3658) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1507255258233&ids=100,135,113,124,114,16,128,117,140,130&isShard=true&shard.url=http://127.0.0.1:39275//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 436565 INFO (qtp22104361-3665) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:39275//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 436565 INFO (qtp24609930-3647) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 436567 INFO (qtp22104361-3659) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=http://127.0.0.1:39275//collection1&rows=10&version=2&q=*:*&NOW=1507255258236&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 436567 INFO (qtp22104361-3658) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1507255258236&ids=11,111,13,123,3,125,104,6,108,109&isShard=true&shard.url=http://127.0.0.1:39275//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 436568 INFO (qtp22104361-3663) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:39275//collection1&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=2 [junit4] 2> 436569 INFO (qtp24609930-3648) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 436570 INFO (qtp22104361-3665) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=http://127.0.0.1:39275//collection1&rows=10&version=2&q=*:*&NOW=1507255258240&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 436571 INFO (qtp22104361-3665) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1507255258240&ids=110,1,112,145,102,148,17,9,142,120&isShard=true&shard.url=http://127.0.0.1:39275//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 436572 INFO (qtp22104361-3664) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:39275//collection1&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2 [junit4] 2> 436573 INFO (qtp24609930-3646) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 436574 INFO (qtp22104361-3665) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=http://127.0.0.1:39275//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1507255258243&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 436575 INFO (qtp22104361-3663) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1507255258243&ids=135,14,3,136,104,137,5,116,118,10&isShard=true&shard.url=http://127.0.0.1:39275//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 436575 INFO (qtp22104361-3659) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:39275//collection1|[ff01::213]:33332/&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 436576 INFO (qtp24609930-3641) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 436577 INFO (qtp22104361-3664) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:39275//collection1|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1507255258246&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 436578 INFO (qtp22104361-3663) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1507255258246&ids=110,111,101,14,126,116,1001,119,120,142&isShard=true&shard.url=http://127.0.0.1:39275//collection1|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 436578 INFO (qtp22104361-3658) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:39275//collection1|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 436579 INFO (qtp24609930-3647) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 436580 INFO (qtp22104361-3659) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=http://127.0.0.1:39275//collection1&rows=10&version=2&q=*:*&NOW=1507255258250&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 436581 INFO (qtp22104361-3663) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1507255258250&ids=121,11,132,133,124,146,103,6,106,128&isShard=true&shard.url=http://127.0.0.1:39275//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 436581 INFO (qtp22104361-3665) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:39275//collection1&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 436582 INFO (qtp24609930-3648) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 436583 INFO (qtp22104361-3658) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=http://127.0.0.1:39275//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1507255258252&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 436583 INFO (qtp22104361-3663) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1507255258252&ids=111,122,12,101,123,135,147,15,139,118&isShard=true&shard.url=http://127.0.0.1:39275//collection1|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 436584 INFO (qtp22104361-3664) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:39275//collection1|[ff01::083]:33332/&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 436584 INFO (qtp24609930-3646) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 436585 INFO (qtp22104361-3665) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:39275//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1507255258255&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 436586 INFO (qtp22104361-3663) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1507255258255&ids=121,133,134,5,16,105,149,116,106,8&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:39275//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 436586 INFO (qtp22104361-3659) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:39275//collection1|[ff01::213]:33332/&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 436587 INFO (qtp24609930-3641) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 436588 INFO (qtp22104361-3664) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=http://127.0.0.1:39275//collection1&rows=10&version=2&q=*:*&NOW=1507255258258&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 436589 INFO (qtp22104361-3663) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1507255258258&ids=11,1,125,126,17,116,117,9,130,120&isShard=true&shard.url=http://127.0.0.1:39275//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 436589 INFO (qtp22104361-3658) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:39275//collection1&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 436590 INFO (qtp24609930-3647) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 436591 INFO (qtp22104361-3659) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+asc&shard.url=http://127.0.0.1:39275//collection1&rows=10&version=2&q=*:*&NOW=1507255258260&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 436591 INFO (qtp22104361-3663) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1507255258260&ids=110,111,133,147,114,115,148,104,139,141&isShard=true&shard.url=http://127.0.0.1:39275//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 436592 INFO (qtp22104361-3665) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards [...truncated too long message...] :1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 2929245 INFO (jetty-closer-3696-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@c68983{/solr,null,UNAVAILABLE} [junit4] 2> 2929246 WARN (zkCallback-3865-thread-2-processing-n:127.0.0.1:33603_solr) [n:127.0.0.1:33603_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 2929246 WARN (zkCallback-3760-thread-3-processing-n:127.0.0.1:33533_solr) [n:127.0.0.1:33533_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 2929247 INFO (jetty-closer-3696-thread-5) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@55ac40{/solr,null,UNAVAILABLE} [junit4] 2> 2929247 INFO (jetty-closer-3696-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@19dbaf3{/solr,null,UNAVAILABLE} [junit4] 2> 2929247 ERROR (SUITE-TriggerIntegrationTest-seed#[E71D439256524ADD]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 2929247 INFO (SUITE-TriggerIntegrationTest-seed#[E71D439256524ADD]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44003 44003 [junit4] 2> 2929360 INFO (Thread-3869) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44003 44003 [junit4] 2> 2929361 WARN (Thread-3869) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 50 /solr/aliases.json [junit4] 2> 50 /solr/clusterprops.json [junit4] 2> 14 /solr/security.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 50 /solr/clusterstate.json [junit4] 2> 6 /solr/autoscaling.json [junit4] 2> 3 /solr/overseer_elect/election/98779636444168231-127.0.0.1:38519_solr-n_0000000007 [junit4] 2> 2 /solr/overseer_elect/election/98779636444168219-127.0.0.1:33533_solr-n_0000000005 [junit4] 2> 2 /solr/overseer_elect/election/98779636444168246-127.0.0.1:44353_solr-n_0000000009 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 50 /solr/collections [junit4] 2> 42 /solr/live_nodes [junit4] 2> 6 /solr/autoscaling/events/.auto_add_replicas [junit4] 2> 6 /solr/overseer/queue-work [junit4] 2> 6 /solr/overseer/collection-queue-work [junit4] 2> 4 /solr/overseer/queue [junit4] 2> 3 /solr/autoscaling/events/node_added_trigger [junit4] 2> 2 /solr/autoscaling/events/node_lost_trigger [junit4] 2> 2 /solr/autoscaling/events/node_added_trigger1 [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_E71D439256524ADD-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=926, maxMBSortInHeap=7.944591706931849, sim=RandomSimilarity(queryNorm=true): {}, locale=cs, timezone=Asia/Irkutsk [junit4] 2> NOTE: Linux 4.10.0-33-generic i386/Oracle Corporation 1.8.0_144 (32-bit)/cpus=8,threads=1,free=155345960,total=518979584 [junit4] 2> NOTE: All tests run in this JVM: [TestReloadDeadlock, DistribCursorPagingTest, TestTrie, HdfsTlogReplayBufferedWhileIndexingTest, AutoCommitTest, DistributedQueryComponentCustomSortTest, LeaderElectionTest, DirectoryFactoryTest, TestShortCircuitedRequests, SmileWriterTest, TestReload, TestOrdValues, SuggesterFSTTest, HardAutoCommitTest, TestSolrCoreProperties, AutoScalingHandlerTest, CopyFieldTest, NodeLostTriggerTest, CurrencyFieldTypeTest, TestSolrCloudWithDelegationTokens, UniqFieldsUpdateProcessorFactoryTest, TestApiFramework, TestSolrDeletionPolicy2, TestCodecSupport, SolrCoreCheckLockOnStartupTest, TestInPlaceUpdatesDistrib, TestZkChroot, TestLocalFSCloudBackupRestore, DistributedFacetPivotSmallAdvancedTest, LargeFieldTest, TestPseudoReturnFields, BlockDirectoryTest, TestGroupingSearch, SolrJmxReporterCloudTest, SolrCoreMetricManagerTest, DateMathParserTest, TestComplexPhraseLeadingWildcard, TestDFISimilarityFactory, DateFieldTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestCryptoKeys, DirectUpdateHandlerTest, SolrMetricsIntegrationTest, GraphQueryTest, TestSchemaNameResource, NotRequiredUniqueKeyTest, MoveReplicaTest, TestIndexSearcher, TestEmbeddedSolrServerConstructors, TestHttpShardHandlerFactory, TestNoOpRegenerator, SpellPossibilityIteratorTest, ForceLeaderTest, PrimitiveFieldTypeTest, TestManagedStopFilterFactory, InfoHandlerTest, CleanupOldIndexTest, ResponseLogComponentTest, OverseerTest, TestSchemalessBufferedUpdates, TestNumericTerms32, TestStressReorder, BaseCdcrDistributedZkTest, TestOnReconnectListenerSupport, TestQuerySenderListener, MissingSegmentRecoveryTest, SortByFunctionTest, TestFieldResource, TestSolrCloudSnapshots, TimeZoneUtilsTest, MultiTermTest, HdfsLockFactoryTest, TestDistributedMissingSort, PreAnalyzedFieldTest, MetricUtilsTest, TestSurroundQueryParser, ConnectionReuseTest, UnloadDistributedZkTest, TestHashQParserPlugin, AnalyticsMergeStrategyTest, TestTrieFacet, BitVectorTest, TestSuggestSpellingConverter, TestQueryUtils, DistributedQueueTest, TestNumericRangeQuery32, DeleteInactiveReplicaTest, SuggesterTest, SolrInfoBeanTest, TestExactSharedStatsCache, TestTlogReplica, TestRestManager, TestRecovery, TestAnalyzedSuggestions, TestStressCloudBlindAtomicUpdates, TestHalfAndHalfDocValues, SuggestComponentTest, SearchHandlerTest, TestMacroExpander, MinimalSchemaTest, AddBlockUpdateTest, CollectionsAPISolrJTest, TestLMJelinekMercerSimilarityFactory, FacetPivotSmallTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestSort, TestManagedSchemaThreadSafety, TestNRTOpen, TestAuthorizationFramework, RulesTest, TestCorePropertiesReload, CloudMLTQParserTest, TestMaxScoreQueryParser, TestRestoreCore, TestCloudPseudoReturnFields, ReturnFieldsTest, TestCoreAdminApis, QueryResultKeyTest, SyncSliceTest, OpenCloseCoreStressTest, ShardRoutingTest, TestRandomFaceting, ZkSolrClientTest, TestFaceting, TestHashPartitioner, DistributedTermsComponentTest, SimpleFacetsTest, StatsComponentTest, SpellCheckComponentTest, TestFiltering, ShowFileRequestHandlerTest, DistributedQueryElevationComponentTest, SolrIndexSplitterTest, SimplePostToolTest, TestCoreDiscovery, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest, CoreAdminHandlerTest, SolrRequestParserTest, TestFoldingMultitermQuery, DocValuesTest, TestStressLucene, SuggesterWFSTTest, TestCSVLoader, PolyFieldTest, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, TestUpdate, QueryEqualityTest, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, TestRemoteStreaming, TestSolrDeletionPolicy1, XsltUpdateRequestHandlerTest, DebugComponentTest, CacheHeaderTest, IndexBasedSpellCheckerTest, LukeRequestHandlerTest, TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest, TermsComponentTest, DocumentBuilderTest, TestIndexingPerformance, RequiredFieldsTest, LoggingHandlerTest, TestJmxIntegration, BinaryUpdateRequestHandlerTest, TestPHPSerializedResponseWriter, PingRequestHandlerTest, TestComponentsName, SearchHandlerTest, HighlighterConfigTest, UpdateParamsTest, TestSolrIndexConfig, SolrIndexConfigTest, BadComponentTest, TestMergePolicyConfig, TestSearchPerf, NumericFieldsTest, TestElisionMultitermQuery, OutputWriterTest, ExternalFileFieldSortTest, DirectSolrConnectionTest, TestCharFilters, TestDFRSimilarityFactory, TestPerFieldSimilarity, TestIBSimilarityFactory, TestBM25SimilarityFactory, TestFastWriter, ResourceLoaderTest, TestFastOutputStream, ScriptEngineTest, URLClassifyProcessorTest, TestFastLRUCache, ClusterStateTest, TestSolrJ, TestUtils, ZkNodePropsTest, SystemInfoHandlerTest, FileUtilsTest, CircularListTest, DistributedIntervalFacetingTest, SolrTestCaseJ4Test, TestCursorMarkWithoutUniqueKey, TestHighlightDedupGrouping, TestSimpleTrackingShardHandler, TestTolerantSearch, TestLuceneIndexBackCompat, TestEmbeddedSolrServerAdminHandler, TestJettySolrRunner, ActionThrottleTest, AssignTest, AsyncCallRequestStatusResponseTest, CdcrBootstrapTest, ChaosMonkeySafeLeaderWithPullReplicasTest, CollectionStateFormat2Test, CollectionTooManyReplicasTest, ConfigSetsAPITest, ConnectionManagerTest, DeleteNodeTest, DeleteReplicaTest, DeleteShardTest, DistribJoinFromCollectionTest, DocValuesNotIndexedTest, HttpPartitionTest, MultiThreadedOCPTest, OverseerTaskQueueTest, RemoteQueryErrorTest, ReplaceNodeTest, SSLMigrationTest, SaslZkACLProviderTest, ShardSplitTest, TestSkipOverseerOperations, ZkFailoverTest, TriggerIntegrationTest] [junit4] Completed [584/745 (2!)] on J2 in 114.90s, 10 tests, 1 failure <<< FAILURES! [...truncated 43183 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:826: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:706: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:693: Source checkout is dirty (unversioned/missing files) after running tests!!! Offending files: * lucene/licenses/morfologik-ukrainian-search-3.7.5.jar.sha1 Total time: 85 minutes 46 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts [WARNINGS] Skipping publisher since build result is FAILURE Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org